builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-011 starttime: 1505620817.52 results: success (0) revision: c079f3f1d24a9797205ba210e7f6a5d21d8c609b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.525113) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.525552) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.525826) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.690711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.691135) ========= 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=5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-16 21:00:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2017-09-16 21:00:17 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129038 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.856519) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.856833) ========= 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=5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014845 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:17.993460) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-16 21:00:17.993889) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c079f3f1d24a9797205ba210e7f6a5d21d8c609b --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 c079f3f1d24a9797205ba210e7f6a5d21d8c609b --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=5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-16 21:00:18,018 truncating revision to first 12 chars 2017-09-16 21:00:18,018 Setting DEBUG logging. 2017-09-16 21:00:18,018 attempt 1/10 2017-09-16 21:00:18,018 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c079f3f1d24a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-16 21:00:18,388 attempt 1/10 2017-09-16 21:00:19,018 current task status: no status available at this point. state: STARTED 2017-09-16 21:00:19,018 sleeping for 10.00s (attempt 1/10) 2017-09-16 21:00:29,028 attempt 2/10 2017-09-16 21:00:29,343 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-16 21:00:29,609 unpacking tar archive at: mozilla-central-c079f3f1d24a/testing/mozharness/ program finished with exit code 0 elapsedTime=11.893706 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-16 21:00:29.917382) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:29.917755) ========= script_repo_revision: c079f3f1d24a9797205ba210e7f6a5d21d8c609b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:29.918148) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:29.918420) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 21:00:29.932330) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-16 21:00:29.932708) ========= /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=5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:00:30 INFO - MultiFileLogger online at 20170916 21:00:30 in /builds/slave/test 21:00:30 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 21:00:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:00:30 INFO - {'append_to_log': False, 21:00:30 INFO - 'base_work_dir': '/builds/slave/test', 21:00:30 INFO - 'blob_upload_branch': 'Firefox', 21:00:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:00:30 INFO - 'branch': 'Firefox', 21:00:30 INFO - 'buildbot_json_path': 'buildprops.json', 21:00:30 INFO - 'code_coverage': False, 21:00:30 INFO - 'config_files': ('talos/linux_config.py',), 21:00:30 INFO - 'default_actions': ('clobber', 21:00:30 INFO - 'read-buildbot-config', 21:00:30 INFO - 'download-and-extract', 21:00:30 INFO - 'populate-webroot', 21:00:30 INFO - 'create-virtualenv', 21:00:30 INFO - 'install', 21:00:30 INFO - 'setup-mitmproxy', 21:00:30 INFO - 'run-tests'), 21:00:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:00:30 INFO - 'disable_ccov_upload': False, 21:00:30 INFO - 'disable_stylo': False, 21:00:30 INFO - 'download_minidump_stackwalk': True, 21:00:30 INFO - 'download_symbols': 'ondemand', 21:00:30 INFO - 'enable_stylo': False, 21:00:30 INFO - 'enable_webrender': False, 21:00:30 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 21:00:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:00:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:00:30 INFO - 'gecko_profile': False, 21:00:30 INFO - 'gecko_profile_interval': 0, 21:00:30 INFO - 'installer_path': 'installer.exe', 21:00:30 INFO - 'log_level': 'info', 21:00:30 INFO - 'log_name': 'talos', 21:00:30 INFO - 'log_to_console': True, 21:00:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:00:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:00:30 INFO - 'opt_config_files': (), 21:00:30 INFO - 'pip_index': False, 21:00:30 INFO - 'suite': 'dromaeojs-e10s', 21:00:30 INFO - 'system_bits': '32', 21:00:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:00:30 INFO - 'title': 'talos-linux64-ix-011', 21:00:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:00:30 INFO - 'use_talos_json': True, 21:00:30 INFO - 'verify': 'False', 21:00:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:00:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:00:30 INFO - 'work_dir': 'build'} 21:00:30 INFO - [mozharness: 2017-09-17 04:00:30.086044Z] Running clobber step. 21:00:30 INFO - Running pre-action listener: _resource_record_pre_action 21:00:30 INFO - Running main action method: clobber 21:00:30 INFO - rmtree: /builds/slave/test/build 21:00:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:00:31 INFO - Running post-action listener: _resource_record_post_action 21:00:31 INFO - [mozharness: 2017-09-17 04:00:31.601408Z] Finished clobber step (success) 21:00:31 INFO - [mozharness: 2017-09-17 04:00:31.601505Z] Running read-buildbot-config step. 21:00:31 INFO - Running pre-action listener: _resource_record_pre_action 21:00:31 INFO - Running main action method: read_buildbot_config 21:00:31 INFO - Using buildbot properties: 21:00:31 INFO - { 21:00:31 INFO - "project": "", 21:00:31 INFO - "product": "firefox", 21:00:31 INFO - "who": "ryanvm@gmail.com", 21:00:31 INFO - "installer_path": "public/build/target.tar.bz2", 21:00:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 21:00:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 21:00:31 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 21:00:31 INFO - "stage_platform": "linux64-qr", 21:00:31 INFO - "basedir": "/builds/slave/test", 21:00:31 INFO - "buildnumber": 66, 21:00:31 INFO - "platform": "ubuntu64_hw_qr", 21:00:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:00:31 INFO - "slavebuilddir": "test", 21:00:31 INFO - "taskId": "FnsHPhzCROGF2u0FVO6mCg", 21:00:31 INFO - "branch": "mozilla-central", 21:00:31 INFO - "script_repo_revision": "production", 21:00:31 INFO - "revision": "c079f3f1d24a9797205ba210e7f6a5d21d8c609b", 21:00:31 INFO - "slavename": "talos-linux64-ix-011", 21:00:31 INFO - "repo_path": "mozilla-central" 21:00:31 INFO - } 21:00:31 INFO - Finding installer, test and symbols from parent task. 21:00:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FnsHPhzCROGF2u0FVO6mCg'}, attempt #1 21:00:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FnsHPhzCROGF2u0FVO6mCg'}, attempt #1 21:00:32 INFO - Task dependencies: CfqVyKmfSpuuqANp3SS4QQ 21:00:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ'}, attempt #1 21:00:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 21:00:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.test_packages.json 21:00:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.crashreporter-symbols.zip 21:00:32 INFO - Running post-action listener: _resource_record_post_action 21:00:32 INFO - [mozharness: 2017-09-17 04:00:32.974229Z] Finished read-buildbot-config step (success) 21:00:32 INFO - [mozharness: 2017-09-17 04:00:32.974445Z] Running download-and-extract step. 21:00:32 INFO - Running pre-action listener: _resource_record_pre_action 21:00:32 INFO - Running main action method: download_and_extract 21:00:32 INFO - mkdir: /builds/slave/test/build/tests 21:00:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:32 INFO - trying https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.test_packages.json 21:00:32 INFO - Downloading https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:00:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:00:33 INFO - Downloaded 989 bytes. 21:00:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:00:33 INFO - Using the following test package requirements: 21:00:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:00:33 INFO - u'common': [u'target.common.tests.zip'], 21:00:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:00:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:00:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:00:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:00:33 INFO - u'mozbase': [u'target.common.tests.zip'], 21:00:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:00:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:00:33 INFO - u'web-platform': [u'target.common.tests.zip', 21:00:33 INFO - u'target.web-platform.tests.tar.gz'], 21:00:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:00:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 21:00:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.common.tests.zip 21:00:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:00:33 INFO - Fetch https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.common.tests.zip into memory 21:00:35 INFO - Content-Length response header: 54731232 21:00:35 INFO - Bytes received: 54731232 21:00:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.talos.tests.zip 21:00:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:00:45 INFO - Fetch https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.talos.tests.zip into memory 21:00:46 INFO - Content-Length response header: 14508748 21:00:46 INFO - Bytes received: 14508748 21:00:46 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')]} 21:00:46 INFO - trying https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 21:00:46 INFO - Downloading https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:00:48 INFO - Downloaded 61581965 bytes. 21:00:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 21:00:48 INFO - mkdir: /builds/slave/test/properties 21:00:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:00:48 INFO - Writing to file /builds/slave/test/properties/build_url 21:00:48 INFO - Contents: 21:00:48 INFO - build_url:https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 21:00:48 INFO - Running post-action listener: _resource_record_post_action 21:00:48 INFO - Running post-action listener: find_tests_for_verification 21:00:48 INFO - Running post-action listener: set_extra_try_arguments 21:00:48 INFO - [mozharness: 2017-09-17 04:00:48.283707Z] Finished download-and-extract step (success) 21:00:48 INFO - [mozharness: 2017-09-17 04:00:48.283805Z] Running populate-webroot step. 21:00:48 INFO - Running pre-action listener: _resource_record_pre_action 21:00:48 INFO - Running main action method: populate_webroot 21:00:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:00:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['tresize', 21:00:48 INFO - 'tcanvasmark']}, 21:00:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:00:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['dromaeo_css', 21:00:48 INFO - 'kraken']}, 21:00:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:00:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:00:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:48 INFO - 'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:00:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:00:48 INFO - 'tests': ['damp', 'tps']}, 21:00:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:48 INFO - 'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['damp', 'tps']}, 21:00:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:00:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['dromaeo_dom']}, 21:00:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:00:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['basic_compositor_video', 21:00:48 INFO - 'glvideo']}, 21:00:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:00:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:00:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:48 INFO - 'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['ts_paint_webext', 21:00:48 INFO - 'tp5o_webext']}, 21:00:48 INFO - 'other-e10s': {'tests': ['a11yr', 21:00:48 INFO - 'ts_paint', 21:00:48 INFO - 'tpaint', 21:00:48 INFO - 'sessionrestore', 21:00:48 INFO - 'sessionrestore_many_windows', 21:00:48 INFO - 'sessionrestore_no_auto_restore', 21:00:48 INFO - 'tabpaint']}, 21:00:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['a11yr', 21:00:48 INFO - 'ts_paint', 21:00:48 INFO - 'tpaint', 21:00:48 INFO - 'sessionrestore', 21:00:48 INFO - 'sessionrestore_many_windows', 21:00:48 INFO - 'sessionrestore_no_auto_restore', 21:00:48 INFO - 'tabpaint']}, 21:00:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 21:00:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 21:00:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['perf_reftest_singletons']}, 21:00:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['bloom_basic']}, 21:00:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:00:48 INFO - 'tsvgr_opacity', 21:00:48 INFO - 'tart', 21:00:48 INFO - 'tscrollx', 21:00:48 INFO - 'tsvg_static']}, 21:00:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['tsvgx', 21:00:48 INFO - 'tsvgr_opacity', 21:00:48 INFO - 'tart', 21:00:48 INFO - 'tscrollx', 21:00:48 INFO - 'tsvg_static']}, 21:00:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:00:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:48 INFO - 'talos_options': ['--disable-stylo'], 21:00:48 INFO - 'tests': ['tp5o']}, 21:00:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:00:48 INFO - 'talos_options': ['--mitmproxy', 21:00:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:00:48 INFO - '--firstNonBlankPaint'], 21:00:48 INFO - 'tests': ['tp6_google', 21:00:48 INFO - 'tp6_youtube', 21:00:48 INFO - 'tp6_amazon', 21:00:48 INFO - 'tp6_facebook']}, 21:00:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:00:48 INFO - 'talos_options': ['--disable-stylo', 21:00:48 INFO - '--mitmproxy', 21:00:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:00:48 INFO - '--firstNonBlankPaint'], 21:00:48 INFO - 'tests': ['tp6_google', 21:00:48 INFO - 'tp6_youtube', 21:00:48 INFO - 'tp6_amazon', 21:00:48 INFO - 'tp6_facebook']}, 21:00:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:00:48 INFO - 'talos_options': ['--stylo-threads=1', 21:00:48 INFO - '--mitmproxy', 21:00:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:00:48 INFO - '--firstNonBlankPaint'], 21:00:48 INFO - 'tests': ['tp6_google', 21:00:48 INFO - 'tp6_youtube', 21:00:48 INFO - 'tp6_amazon', 21:00:48 INFO - 'tp6_facebook']}, 21:00:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:00:48 INFO - 'talos_options': ['--xperf_path', 21:00:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:00:48 INFO - 'tests': ['tp5n']}, 21:00:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:00:48 INFO - 'talos_options': ['--disable-stylo', 21:00:48 INFO - '--xperf_path', 21:00:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:00:48 INFO - 'tests': ['tp5n']}}} 21:00:48 INFO - Running post-action listener: _resource_record_post_action 21:00:48 INFO - [mozharness: 2017-09-17 04:00:48.300617Z] Finished populate-webroot step (success) 21:00:48 INFO - [mozharness: 2017-09-17 04:00:48.300707Z] Running create-virtualenv step. 21:00:48 INFO - Running pre-action listener: _resource_record_pre_action 21:00:48 INFO - Running main action method: create_virtualenv 21:00:48 INFO - Creating virtualenv /builds/slave/test/build/venv 21:00:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:00:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 21:00:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:00:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:00:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:00:48 INFO - Installing distribute.............................................................................................................................................................................................done. 21:00:49 INFO - Installing pip...............done. 21:00:49 INFO - Return code: 0 21:00:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:00:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:00:49 INFO - Reading from file tmpfile_stdout 21:00:49 INFO - Output received: 21:00:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 21:00:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:00:49 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')]} 21:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:49 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')]} 21:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:49 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 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:00:49 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 21:00:49 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 21:00:49 INFO - Using env: {'DISPLAY': ':0', 21:00:49 INFO - 'HOME': '/home/cltbld', 21:00:49 INFO - 'LANG': 'en_US.UTF-8', 21:00:49 INFO - 'LANGUAGE': 'en_US:en', 21:00:49 INFO - 'LOGNAME': 'cltbld', 21:00:49 INFO - 'MAIL': '/var/mail/cltbld', 21:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:49 INFO - 'MOZ_NO_REMOTE': '1', 21:00:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:49 INFO - 'NO_EM_RESTART': '1', 21:00:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:49 INFO - 'PWD': '/builds/slave/test', 21:00:49 INFO - 'SHELL': '/bin/bash', 21:00:49 INFO - 'SHLVL': '1', 21:00:49 INFO - 'TERM': 'linux', 21:00:49 INFO - 'TMOUT': '86400', 21:00:49 INFO - 'USER': 'cltbld', 21:00:49 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:49 INFO - '_': '/tools/buildbot/bin/python'} 21:00:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:00:50 INFO - Downloading/unpacking pip>=1.5 21:00:50 INFO - Running setup.py egg_info for package pip 21:00:50 INFO - warning: no files found matching 'pip/cacert.pem' 21:00:50 INFO - warning: no files found matching '*.html' under directory 'docs' 21:00:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:00:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:00:50 INFO - Installing collected packages: pip 21:00:50 INFO - Found existing installation: pip 1.1 21:00:50 INFO - Uninstalling pip: 21:00:50 INFO - Successfully uninstalled pip 21:00:50 INFO - Running setup.py install for pip 21:00:50 INFO - warning: no files found matching 'pip/cacert.pem' 21:00:50 INFO - warning: no files found matching '*.html' under directory 'docs' 21:00:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:00:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:00:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 21:00:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 21:00:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 21:00:50 INFO - Successfully installed pip 21:00:50 INFO - Cleaning up... 21:00:50 INFO - Return code: 0 21:00:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 21:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 21:00:50 INFO - Using env: {'DISPLAY': ':0', 21:00:50 INFO - 'HOME': '/home/cltbld', 21:00:50 INFO - 'LANG': 'en_US.UTF-8', 21:00:50 INFO - 'LANGUAGE': 'en_US:en', 21:00:50 INFO - 'LOGNAME': 'cltbld', 21:00:50 INFO - 'MAIL': '/var/mail/cltbld', 21:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:50 INFO - 'MOZ_NO_REMOTE': '1', 21:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:50 INFO - 'NO_EM_RESTART': '1', 21:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:50 INFO - 'PWD': '/builds/slave/test', 21:00:50 INFO - 'SHELL': '/bin/bash', 21:00:50 INFO - 'SHLVL': '1', 21:00:50 INFO - 'TERM': 'linux', 21:00:50 INFO - 'TMOUT': '86400', 21:00:50 INFO - 'USER': 'cltbld', 21:00:50 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:50 INFO - '_': '/tools/buildbot/bin/python'} 21:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:50 INFO - Downloading/unpacking psutil>=3.1.1 21:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:00:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:00:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:00:52 INFO - Installing collected packages: psutil 21:00:52 INFO - Running setup.py install for psutil 21:00:52 INFO - building 'psutil._psutil_linux' extension 21:00:52 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 21:00:52 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 21:00:52 INFO - building 'psutil._psutil_posix' extension 21:00:52 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 21:00:52 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 21:00:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:00:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:00:52 INFO - Successfully installed psutil 21:00:52 INFO - Cleaning up... 21:00:52 INFO - Return code: 0 21:00:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:00:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:00:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:00:52 INFO - Using env: {'DISPLAY': ':0', 21:00:52 INFO - 'HOME': '/home/cltbld', 21:00:52 INFO - 'LANG': 'en_US.UTF-8', 21:00:52 INFO - 'LANGUAGE': 'en_US:en', 21:00:52 INFO - 'LOGNAME': 'cltbld', 21:00:52 INFO - 'MAIL': '/var/mail/cltbld', 21:00:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:52 INFO - 'MOZ_NO_REMOTE': '1', 21:00:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:52 INFO - 'NO_EM_RESTART': '1', 21:00:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:52 INFO - 'PWD': '/builds/slave/test', 21:00:52 INFO - 'SHELL': '/bin/bash', 21:00:52 INFO - 'SHLVL': '1', 21:00:52 INFO - 'TERM': 'linux', 21:00:52 INFO - 'TMOUT': '86400', 21:00:52 INFO - 'USER': 'cltbld', 21:00:52 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:00:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:52 INFO - '_': '/tools/buildbot/bin/python'} 21:00:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:00:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:00:53 INFO - Installing collected packages: mozsystemmonitor 21:00:53 INFO - Running setup.py install for mozsystemmonitor 21:00:54 INFO - Successfully installed mozsystemmonitor 21:00:54 INFO - Cleaning up... 21:00:54 INFO - Return code: 0 21:00:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:54 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 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:00:54 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 21:00:54 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 21:00:54 INFO - Using env: {'DISPLAY': ':0', 21:00:54 INFO - 'HOME': '/home/cltbld', 21:00:54 INFO - 'LANG': 'en_US.UTF-8', 21:00:54 INFO - 'LANGUAGE': 'en_US:en', 21:00:54 INFO - 'LOGNAME': 'cltbld', 21:00:54 INFO - 'MAIL': '/var/mail/cltbld', 21:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:54 INFO - 'MOZ_NO_REMOTE': '1', 21:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:54 INFO - 'NO_EM_RESTART': '1', 21:00:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:54 INFO - 'PWD': '/builds/slave/test', 21:00:54 INFO - 'SHELL': '/bin/bash', 21:00:54 INFO - 'SHLVL': '1', 21:00:54 INFO - 'TERM': 'linux', 21:00:54 INFO - 'TMOUT': '86400', 21:00:54 INFO - 'USER': 'cltbld', 21:00:54 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:54 INFO - '_': '/tools/buildbot/bin/python'} 21:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:54 INFO - Downloading/unpacking jsonschema==2.5.1 21:00:54 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 21:00:54 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 21:00:54 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 21:00:54 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 21:00:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:00:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:00:56 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 21:00:56 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 21:00:56 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 21:00:56 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 21:00:56 INFO - Downloading functools32-3.2.3-2.tar.gz 21:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:00:56 INFO - warning: no files found matching '*.txt' 21:00:56 INFO - no previously-included directories found matching 'build' 21:00:56 INFO - no previously-included directories found matching 'dist' 21:00:56 INFO - no previously-included directories found matching '.git*' 21:00:56 INFO - Installing collected packages: jsonschema, functools32 21:00:56 INFO - Running setup.py install for functools32 21:00:57 INFO - warning: no files found matching '*.txt' 21:00:57 INFO - no previously-included directories found matching 'build' 21:00:57 INFO - no previously-included directories found matching 'dist' 21:00:57 INFO - no previously-included directories found matching '.git*' 21:00:57 INFO - Successfully installed jsonschema functools32 21:00:57 INFO - Cleaning up... 21:00:57 INFO - Return code: 0 21:00:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:57 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 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:00:57 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 21:00:57 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 21:00:57 INFO - Using env: {'DISPLAY': ':0', 21:00:57 INFO - 'HOME': '/home/cltbld', 21:00:57 INFO - 'LANG': 'en_US.UTF-8', 21:00:57 INFO - 'LANGUAGE': 'en_US:en', 21:00:57 INFO - 'LOGNAME': 'cltbld', 21:00:57 INFO - 'MAIL': '/var/mail/cltbld', 21:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:57 INFO - 'MOZ_NO_REMOTE': '1', 21:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:57 INFO - 'NO_EM_RESTART': '1', 21:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:57 INFO - 'PWD': '/builds/slave/test', 21:00:57 INFO - 'SHELL': '/bin/bash', 21:00:57 INFO - 'SHLVL': '1', 21:00:57 INFO - 'TERM': 'linux', 21:00:57 INFO - 'TMOUT': '86400', 21:00:57 INFO - 'USER': 'cltbld', 21:00:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:57 INFO - '_': '/tools/buildbot/bin/python'} 21:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:00:57 INFO - Cleaning up... 21:00:57 INFO - Return code: 0 21:00:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:57 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 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:00:57 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 21:00:57 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 21:00:57 INFO - Using env: {'DISPLAY': ':0', 21:00:57 INFO - 'HOME': '/home/cltbld', 21:00:57 INFO - 'LANG': 'en_US.UTF-8', 21:00:57 INFO - 'LANGUAGE': 'en_US:en', 21:00:57 INFO - 'LOGNAME': 'cltbld', 21:00:57 INFO - 'MAIL': '/var/mail/cltbld', 21:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:57 INFO - 'MOZ_NO_REMOTE': '1', 21:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:57 INFO - 'NO_EM_RESTART': '1', 21:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:57 INFO - 'PWD': '/builds/slave/test', 21:00:57 INFO - 'SHELL': '/bin/bash', 21:00:57 INFO - 'SHLVL': '1', 21:00:57 INFO - 'TERM': 'linux', 21:00:57 INFO - 'TMOUT': '86400', 21:00:57 INFO - 'USER': 'cltbld', 21:00:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:57 INFO - '_': '/tools/buildbot/bin/python'} 21:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:00:57 INFO - Downloading/unpacking blobuploader==1.2.4 21:00:57 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 21:00:57 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 21:00:57 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 21:00:57 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 21:00:58 INFO - Downloading blobuploader-1.2.4.tar.gz 21:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:00:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:00:58 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 21:00:58 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 21:00:58 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 21:00:58 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 21:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:00:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:00:59 INFO - Downloading docopt-0.6.1.tar.gz 21:00:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:00:59 INFO - Installing collected packages: blobuploader, requests, docopt 21:00:59 INFO - Running setup.py install for blobuploader 21:00:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:00:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:00:59 INFO - Running setup.py install for requests 21:00:59 INFO - Running setup.py install for docopt 21:00:59 INFO - Successfully installed blobuploader requests docopt 21:00:59 INFO - Cleaning up... 21:00:59 INFO - Return code: 0 21:00:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:00:59 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')]} 21:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:00:59 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')]} 21:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:00:59 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 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:00:59 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 21:00:59 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 21:00:59 INFO - Using env: {'DISPLAY': ':0', 21:00:59 INFO - 'HOME': '/home/cltbld', 21:00:59 INFO - 'LANG': 'en_US.UTF-8', 21:00:59 INFO - 'LANGUAGE': 'en_US:en', 21:00:59 INFO - 'LOGNAME': 'cltbld', 21:00:59 INFO - 'MAIL': '/var/mail/cltbld', 21:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:00:59 INFO - 'MOZ_NO_REMOTE': '1', 21:00:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:00:59 INFO - 'NO_EM_RESTART': '1', 21:00:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:00:59 INFO - 'PWD': '/builds/slave/test', 21:00:59 INFO - 'SHELL': '/bin/bash', 21:00:59 INFO - 'SHLVL': '1', 21:00:59 INFO - 'TERM': 'linux', 21:00:59 INFO - 'TMOUT': '86400', 21:00:59 INFO - 'USER': 'cltbld', 21:00:59 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:00:59 INFO - '_': '/tools/buildbot/bin/python'} 21:01:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:01:00 INFO - Running setup.py (path:/tmp/pip-UgxxaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:01:00 INFO - Running setup.py (path:/tmp/pip-ZbEcQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:01:00 INFO - Running setup.py (path:/tmp/pip-jQZigc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:01:00 INFO - Running setup.py (path:/tmp/pip-tqBNU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:01:00 INFO - Running setup.py (path:/tmp/pip-fNQckb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:01:00 INFO - Running setup.py (path:/tmp/pip-I44VEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:01:00 INFO - Running setup.py (path:/tmp/pip-4OMmuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:01:00 INFO - Running setup.py (path:/tmp/pip-c7_kBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:01:00 INFO - Running setup.py (path:/tmp/pip-sXsC1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:01:00 INFO - Running setup.py (path:/tmp/pip-z_EEaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:01:00 INFO - Running setup.py (path:/tmp/pip-Z17F5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:01:00 INFO - Running setup.py (path:/tmp/pip-K_eXkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:01:00 INFO - Running setup.py (path:/tmp/pip-lloEj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:01:00 INFO - Running setup.py (path:/tmp/pip-H7EInm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:01 INFO - Running setup.py (path:/tmp/pip-MnUrq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:01:01 INFO - Running setup.py (path:/tmp/pip-Z5JElu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:01:01 INFO - Running setup.py (path:/tmp/pip-ZUenWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:01:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:01:01 INFO - Running setup.py install for manifestparser 21:01:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for mozcrash 21:01:01 INFO - Running setup.py install for mozdebug 21:01:01 INFO - Running setup.py install for mozdevice 21:01:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for mozfile 21:01:01 INFO - Running setup.py install for mozhttpd 21:01:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for mozinfo 21:01:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:01:01 INFO - Running setup.py install for mozInstall 21:01:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Running setup.py install for mozleak 21:01:02 INFO - Running setup.py install for mozlog 21:01:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Running setup.py install for moznetwork 21:01:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Running setup.py install for mozprocess 21:01:02 INFO - Running setup.py install for mozprofile 21:01:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Running setup.py install for mozrunner 21:01:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:01:02 INFO - Running setup.py install for mozscreenshot 21:01:02 INFO - Running setup.py install for moztest 21:01:02 INFO - Running setup.py install for mozversion 21:01:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:01:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:01:03 INFO - Cleaning up... 21:01:03 INFO - Return code: 0 21:01:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:03 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 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:01:03 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 21:01:03 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 21:01:03 INFO - Using env: {'DISPLAY': ':0', 21:01:03 INFO - 'HOME': '/home/cltbld', 21:01:03 INFO - 'LANG': 'en_US.UTF-8', 21:01:03 INFO - 'LANGUAGE': 'en_US:en', 21:01:03 INFO - 'LOGNAME': 'cltbld', 21:01:03 INFO - 'MAIL': '/var/mail/cltbld', 21:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:03 INFO - 'MOZ_NO_REMOTE': '1', 21:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:03 INFO - 'NO_EM_RESTART': '1', 21:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:03 INFO - 'PWD': '/builds/slave/test', 21:01:03 INFO - 'SHELL': '/bin/bash', 21:01:03 INFO - 'SHLVL': '1', 21:01:03 INFO - 'TERM': 'linux', 21:01:03 INFO - 'TMOUT': '86400', 21:01:03 INFO - 'USER': 'cltbld', 21:01:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:03 INFO - '_': '/tools/buildbot/bin/python'} 21:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:01:03 INFO - Running setup.py (path:/tmp/pip-AlwJi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:01:03 INFO - Running setup.py (path:/tmp/pip-AhzbEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:01:03 INFO - Running setup.py (path:/tmp/pip-ccg6ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:01:03 INFO - Running setup.py (path:/tmp/pip-9HeV1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:01:03 INFO - Running setup.py (path:/tmp/pip-Xt0WKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:01:03 INFO - Running setup.py (path:/tmp/pip-5RFsxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:01:03 INFO - Running setup.py (path:/tmp/pip-7Xk6z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:01:03 INFO - Running setup.py (path:/tmp/pip-hdz0rs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:01:03 INFO - Running setup.py (path:/tmp/pip-jMM4hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:01:03 INFO - Running setup.py (path:/tmp/pip-hJ1f6J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:01:03 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)) 21:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:01:03 INFO - Running setup.py (path:/tmp/pip-tlZeBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:01:04 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)) 21:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:01:04 INFO - Running setup.py (path:/tmp/pip-SBxUzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:01:04 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)) 21:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:01:04 INFO - Running setup.py (path:/tmp/pip-Jnyhcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:01:04 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)) 21:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:01:04 INFO - Running setup.py (path:/tmp/pip-1XbHGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:01:04 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)) 21:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:04 INFO - Running setup.py (path:/tmp/pip-L1N656-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:01:04 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)) 21:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:01:04 INFO - Running setup.py (path:/tmp/pip-eoy9Ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:01:04 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)) 21:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:01:04 INFO - Running setup.py (path:/tmp/pip-UMZuMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:01:04 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)) 21:01:04 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)) 21:01:04 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)) 21:01:04 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)) 21:01:04 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)) 21:01:04 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)) 21:01:04 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)) 21:01:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:01:04 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 21:01:04 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 21:01:04 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 21:01:04 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 21:01:05 INFO - Downloading blessings-1.6.tar.gz 21:01:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:01:05 INFO - Installing collected packages: blessings 21:01:05 INFO - Running setup.py install for blessings 21:01:05 INFO - Successfully installed blessings 21:01:05 INFO - Cleaning up... 21:01:05 INFO - Return code: 0 21:01:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:01:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:06 INFO - Reading from file tmpfile_stdout 21:01:06 INFO - Current package versions: 21:01:06 INFO - argparse == 1.2.1 21:01:06 INFO - blessings == 1.6 21:01:06 INFO - blobuploader == 1.2.4 21:01:06 INFO - docopt == 0.6.1 21:01:06 INFO - functools32 == 3.2.3-2 21:01:06 INFO - jsonschema == 2.5.1 21:01:06 INFO - manifestparser == 1.1 21:01:06 INFO - mozInstall == 1.13 21:01:06 INFO - mozcrash == 1.0 21:01:06 INFO - mozdebug == 0.1 21:01:06 INFO - mozdevice == 0.51 21:01:06 INFO - mozfile == 1.2 21:01:06 INFO - mozhttpd == 0.7 21:01:06 INFO - mozinfo == 0.10 21:01:06 INFO - mozleak == 0.1 21:01:06 INFO - mozlog == 3.5 21:01:06 INFO - moznetwork == 0.27 21:01:06 INFO - mozprocess == 0.25 21:01:06 INFO - mozprofile == 0.28 21:01:06 INFO - mozrunner == 6.13 21:01:06 INFO - mozscreenshot == 0.1 21:01:06 INFO - mozsystemmonitor == 0.3 21:01:06 INFO - moztest == 0.8 21:01:06 INFO - mozversion == 1.4 21:01:06 INFO - psutil == 3.1.1 21:01:06 INFO - requests == 1.2.3 21:01:06 INFO - wsgiref == 0.1.2 21:01:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:01: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')]} 21:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01: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')]} 21:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:06 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 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:01:06 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 21:01:06 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 21:01:06 INFO - Using env: {'DISPLAY': ':0', 21:01:06 INFO - 'HOME': '/home/cltbld', 21:01:06 INFO - 'LANG': 'en_US.UTF-8', 21:01:06 INFO - 'LANGUAGE': 'en_US:en', 21:01:06 INFO - 'LOGNAME': 'cltbld', 21:01:06 INFO - 'MAIL': '/var/mail/cltbld', 21:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:06 INFO - 'MOZ_NO_REMOTE': '1', 21:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:06 INFO - 'NO_EM_RESTART': '1', 21:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:06 INFO - 'PWD': '/builds/slave/test', 21:01:06 INFO - 'SHELL': '/bin/bash', 21:01:06 INFO - 'SHLVL': '1', 21:01:06 INFO - 'TERM': 'linux', 21:01:06 INFO - 'TMOUT': '86400', 21:01:06 INFO - 'USER': 'cltbld', 21:01:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:06 INFO - '_': '/tools/buildbot/bin/python'} 21:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:06 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)) 21:01:06 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)) 21:01:06 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)) 21:01:06 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)) 21:01:06 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)) 21:01:06 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)) 21:01:06 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)) 21:01:06 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)) 21:01:06 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)) 21:01:06 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:01:06 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 21:01:06 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 21:01:06 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 21:01:06 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 21:01:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 21:01:07 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)) 21:01:07 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)) 21:01:07 INFO - Installing collected packages: simplejson 21:01:07 INFO - Running setup.py install for simplejson 21:01:07 INFO - building 'simplejson._speedups' extension 21:01:07 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 21:01:08 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 21:01:08 INFO - Successfully installed simplejson 21:01:08 INFO - Cleaning up... 21:01:08 INFO - Return code: 0 21:01:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:01:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27cfa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, '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': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', '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 21:01:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 21:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:01:08 INFO - Using env: {'DISPLAY': ':0', 21:01:08 INFO - 'HOME': '/home/cltbld', 21:01:08 INFO - 'LANG': 'en_US.UTF-8', 21:01:08 INFO - 'LANGUAGE': 'en_US:en', 21:01:08 INFO - 'LOGNAME': 'cltbld', 21:01:08 INFO - 'MAIL': '/var/mail/cltbld', 21:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:08 INFO - 'MOZ_NO_REMOTE': '1', 21:01:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:08 INFO - 'NO_EM_RESTART': '1', 21:01:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:08 INFO - 'PWD': '/builds/slave/test', 21:01:08 INFO - 'SHELL': '/bin/bash', 21:01:08 INFO - 'SHLVL': '1', 21:01:08 INFO - 'TERM': 'linux', 21:01:08 INFO - 'TMOUT': '86400', 21:01:08 INFO - 'USER': 'cltbld', 21:01:08 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:08 INFO - '_': '/tools/buildbot/bin/python'} 21:01:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:01:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:01:08 INFO - Cleaning up... 21:01:08 INFO - Return code: 0 21:01:08 INFO - Running post-action listener: _resource_record_post_action 21:01:08 INFO - Running post-action listener: _start_resource_monitoring 21:01:08 INFO - Starting resource monitoring. 21:01:08 INFO - [mozharness: 2017-09-17 04:01:08.772466Z] Finished create-virtualenv step (success) 21:01:08 INFO - [mozharness: 2017-09-17 04:01:08.772976Z] Running install step. 21:01:08 INFO - Running pre-action listener: _resource_record_pre_action 21:01:08 INFO - Running main action method: install 21:01:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:01:08 INFO - Reading from file tmpfile_stdout 21:01:08 INFO - Detecting whether we're running mozinstall >=1.0... 21:01:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:01:09 INFO - Reading from file tmpfile_stdout 21:01:09 INFO - Output received: 21:01:09 INFO - Usage: mozinstall [options] installer 21:01:09 INFO - Options: 21:01:09 INFO - -h, --help show this help message and exit 21:01:09 INFO - -d DEST, --destination=DEST 21:01:09 INFO - Directory to install application into. [default: 21:01:09 INFO - "/builds/slave/test"] 21:01:09 INFO - --app=APP Application being installed. [default: firefox] 21:01:09 INFO - mkdir: /builds/slave/test/build/application 21:01:09 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'] 21:01:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:01:26 INFO - Reading from file tmpfile_stdout 21:01:26 INFO - Output received: 21:01:26 INFO - /builds/slave/test/build/application/firefox/firefox 21:01:26 INFO - Running post-action listener: _resource_record_post_action 21:01:26 INFO - [mozharness: 2017-09-17 04:01:26.932609Z] Finished install step (success) 21:01:26 INFO - [mozharness: 2017-09-17 04:01:26.932800Z] Running setup-mitmproxy step. 21:01:26 INFO - Running pre-action listener: _resource_record_pre_action 21:01:26 INFO - Running main action method: setup_mitmproxy 21:01:26 INFO - Skipping: mitmproxy is not required 21:01:26 INFO - Running post-action listener: _resource_record_post_action 21:01:26 INFO - [mozharness: 2017-09-17 04:01:26.933938Z] Finished setup-mitmproxy step (success) 21:01:26 INFO - [mozharness: 2017-09-17 04:01:26.934158Z] Running run-tests step. 21:01:26 INFO - Running pre-action listener: _resource_record_pre_action 21:01:26 INFO - Running pre-action listener: _set_gcov_prefix 21:01:26 INFO - Running main action method: run_tests 21:01:26 WARNING - Try message not found. 21:01:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:01:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:01:26 INFO - Python 2.7.3 21:01:26 INFO - Return code: 0 21:01:26 INFO - grabbing minidump binary from tooltool 21:01:26 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')]} 21:01:26 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27d3090>, '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 0x27d3d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27cdf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:01:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:01:26 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:01:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:01:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:01:27 INFO - Return code: 0 21:01:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:01:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:01:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:01:27 INFO - ENV: RUST_BACKTRACE is now full 21:01:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:01:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:01:27 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/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 21:01:27 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/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 21:01:27 INFO - Using env: {'DISPLAY': ':0', 21:01:27 INFO - 'HOME': '/home/cltbld', 21:01:27 INFO - 'LANG': 'en_US.UTF-8', 21:01:27 INFO - 'LANGUAGE': 'en_US:en', 21:01:27 INFO - 'LOGNAME': 'cltbld', 21:01:27 INFO - 'MAIL': '/var/mail/cltbld', 21:01:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:01:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:01:27 INFO - 'MOZ_ACCELERATED': '1', 21:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:27 INFO - 'MOZ_NO_REMOTE': '1', 21:01:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:01:27 INFO - 'MOZ_WEBRENDER': '1', 21:01:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:27 INFO - 'NO_EM_RESTART': '1', 21:01:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:27 INFO - 'PWD': '/builds/slave/test', 21:01:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:01:27 INFO - 'RUST_BACKTRACE': 'full', 21:01:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:01:27 INFO - 'SHELL': '/bin/bash', 21:01:27 INFO - 'SHLVL': '1', 21:01:27 INFO - 'TERM': 'linux', 21:01:27 INFO - 'TMOUT': '86400', 21:01:27 INFO - 'USER': 'cltbld', 21:01:27 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:27 INFO - '_': '/tools/buildbot/bin/python'} 21:01:27 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/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 21:01:27 INFO - mozversion application_buildid: 20170917030923 21:01:27 INFO - mozversion application_changeset: c079f3f1d24a9797205ba210e7f6a5d21d8c609b 21:01:27 INFO - mozversion application_display_name: Nightly 21:01:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:01:27 INFO - mozversion application_name: Firefox 21:01:27 INFO - mozversion application_remotingname: firefox 21:01:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:01:27 INFO - mozversion application_vendor: Mozilla 21:01:27 INFO - mozversion application_version: 57.0a1 21:01:27 INFO - mozversion platform_buildid: 20170917030923 21:01:27 INFO - mozversion platform_changeset: c079f3f1d24a9797205ba210e7f6a5d21d8c609b 21:01:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:01:27 INFO - mozversion platform_version: 57.0a1 21:01:27 INFO - using testdate: 1505620887 21:01:27 INFO - actual date: 1505620887 21:01:27 INFO - starting webserver on 'localhost:51993' 21:01:27 INFO - SUITE-START | Running 2 tests 21:01:27 INFO - TEST-START | dromaeo_css 21:01:27 INFO - Initialising browser for dromaeo_css test... 21:01:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51993/getInfo.html -profile /tmp/tmpeLCQy4/profile 21:01:27 INFO - TEST-INFO | started process 12481 (/builds/slave/test/build/application/firefox/firefox http://localhost:51993/getInfo.html) 21:01:35 INFO - TEST-INFO | 12481: exit 0 21:01:35 INFO - Browser initialized. 21:01:35 INFO - Running cycle 1/1 for dromaeo_css test... 21:01:35 INFO - Using env: {'DISPLAY': ':0', 21:01:35 INFO - 'HOME': '/home/cltbld', 21:01:35 INFO - 'JSGC_DISABLE_POISONING': '1', 21:01:35 INFO - 'LANG': 'en_US.UTF-8', 21:01:35 INFO - 'LANGUAGE': 'en_US:en', 21:01:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:01:35 INFO - 'LOGNAME': 'cltbld', 21:01:35 INFO - 'MAIL': '/var/mail/cltbld', 21:01:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:01:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:01:35 INFO - 'MOZ_ACCELERATED': '1', 21:01:35 INFO - 'MOZ_CRASHREPORTER': '1', 21:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:01:35 INFO - 'MOZ_NO_REMOTE': '1', 21:01:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:01:35 INFO - 'MOZ_WEBRENDER': '1', 21:01:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:35 INFO - 'NO_EM_RESTART': '1', 21:01:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:35 INFO - 'PWD': '/builds/slave/test', 21:01:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:01:35 INFO - 'RUST_BACKTRACE': 'full', 21:01:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:01:35 INFO - 'SHELL': '/bin/bash', 21:01:35 INFO - 'SHLVL': '1', 21:01:35 INFO - 'TERM': 'linux', 21:01:35 INFO - 'TMOUT': '86400', 21:01:35 INFO - 'USER': 'cltbld', 21:01:35 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:35 INFO - '_': '/tools/buildbot/bin/python'} 21:01:35 INFO - TEST-INFO | started process 12725 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeLCQy4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:01:36 INFO - PID 12725 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 21:01:37 INFO - PID 12725 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 21:01:37 INFO - PID 12725 | 21:01:37 INFO - PID 12725 | (/builds/slave/test/build/application/firefox/firefox:12785): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:01:37 INFO - PID 12725 | 21:01:47 INFO - PID 12725 | RSS: Main: 207679488 21:01:47 INFO - PID 12725 | 21:04:45 INFO - PID 12725 | Cycle 1(1): loaded http://localhost:51993/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51993/tests/dromaeo/cssquery-ext.html) 21:04:46 INFO - PID 12725 | RSS: Main: 205406208 21:04:46 INFO - PID 12725 | 21:07:48 INFO - PID 12725 | Cycle 1(1): loaded http://localhost:51993/tests/dromaeo/cssquery-ext.html (next: http://localhost:51993/tests/dromaeo/cssquery-jquery.html) 21:07:49 INFO - PID 12725 | RSS: Main: 206901248 21:07:49 INFO - PID 12725 | 21:10:51 INFO - PID 12725 | Cycle 1(1): loaded http://localhost:51993/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51993/tests/dromaeo/cssquery-mootools.html) 21:10:52 INFO - PID 12725 | RSS: Main: 208461824 21:10:52 INFO - PID 12725 | 21:13:54 INFO - PID 12725 | Cycle 1(1): loaded http://localhost:51993/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51993/tests/dromaeo/cssquery-prototype.html) 21:13:54 INFO - PID 12725 | RSS: Main: 207503360 21:13:54 INFO - PID 12725 | 21:16:58 INFO - PID 12725 | Cycle 1(1): loaded http://localhost:51993/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51993/tests/dromaeo/cssquery-yui.html) 21:16:59 INFO - PID 12725 | RSS: Main: 212566016 21:16:59 INFO - PID 12725 | 21:20:01 INFO - PID 12725 | Cycle 1(1): loaded http://localhost:51993/tests/dromaeo/cssquery-yui.html (next: http://localhost:51993/tests/dromaeo/cssquery-dojo.html) 21:20:01 INFO - PID 12725 | RSS: Main: 211525632 21:20:01 INFO - PID 12725 | 21:20:01 INFO - PID 12725 | __start_tp_report 21:20:01 INFO - PID 12725 | _x_x_mozilla_page_load 21:20:01 INFO - PID 12725 | _x_x_mozilla_page_load_details 21:20:01 INFO - PID 12725 | |i|pagename|runs| 21:20:01 INFO - PID 12725 | |0;dojo.html;3125.5467957146216;3140.9842950785255;3148.338848841743;3185.1718553176165;3188.6173659160895;6150.6924653767355;6196.194494715686;6276.937230627693;6280.371962803717;6315.40003699649;6155.907661385072;6200.813975580729;6255.655938923357;6259.155014073111;6265.9686701566425;5701.287339082614;5732.082866741312;5734.455226753452;5771.134329850514;5908.379620139888;5754.251947246852;5814.883702325972;5822.039363505017;5868.295804503444;5869.85325366865;5747.741351639186;5825.737841797109;5831.096180092092;5894.322152952401;5906.645601263916;5564.1931919871395;5696.3733989261145;5749.568782341341;5763.567732420043;5794.449527294901;5701.315842098933;5739.569532285095;5773.422657734235;5792.623479473821;5825.9417405825825;6022.187004754346;6155.199824022849;6181.876362472771;6355.428011478989;6372.968135159341;6353.4281914627445;6390.329015453403;6418.133551970471;6444.677766111676;6453.548251622388;6683.365080317362;6770.356816102462;6771.966140169267;6788.966055169742;6804.047433359334;6291.276503202191;6326.68366581669;6353.332900045475;6363.140975968231;6370.5540612157165;6865.931340686529;6927.3419025192525;7000.26497217795;7027.683754231072;7040;6423.775167869102;6458.160439142881;6487.18910136233;6507.3167317431935;6646.567973081735;6573.967130164318;6633.900491492632;6650.567713098633;6652.268250492442;6654.301298363602;6556.147700798961;6577.901331480031;6605.570637908617;6607.735690572419;6612.7354905804195;6729.629870357079;6756.155480564929;6788.9660551696925;6813.965930170318;6841.3842754152365;5283.339582552181;5312.548433383128;5356.517913387814;5359.464053594609;5360.249565060894;4505.459344878635;4581.404417425713;4603.838865639686;4644.558766917137;4648.581627653527;5702.971485142548;5783;5794.768209271666;5797.072468405035;5893.970530147408;6627.3372662733345;6699.095622091053;6715.1941766988275;6757.831054223586;6764.120664313707;5821.912671309934;5827.650340979555;5864.912026319608;5865.794697185578;5887.293524777054;5761.711914404263;5773;5842.970785146047;5856.267966504187;5856.619319744203;6392.296847346788;6467.708953097122;6478.481721462272;6478.967605161944;6482.611043337415;6556.63938483388;6601.603903765789;6603.174603174603;6631.834204144935;6648.501362397937;6556.442702370352;6589.868202636017;6640.435562977201;6656.83357916056;6692.196936367667;6555.672216389066;6643.468522518284;6682.298358672269;6729.192496900403;6769.729210831511;6724.7646332378135;6746.696398661974;6841.62371069596;6843.863122737418;6845.589264643938;6373.521985850987;6377.87244255103;6433.195850518685;6479.7408103675325;6555.639439830855;6497.805065848032;6505.154329937078;6542.182227221598;6551.705173267119;6584.506162037962;6352.745890164527;6510.121133646897;6512.869742605216;6525.771597994238;6541.967290163519;6514.218293804584;6570;6584.901226481511;6651.800445986628;6675.265720770809;6121.663308518075;6147.569670123048;6228.968855155696;6248.937510625017;6259 21:20:01 INFO - PID 12725 | |1;ext.html;750.4596690382924;771.5023809642779;772.7102336623766;781.3905153979894;785.140271402814;38777.41833872491;39522.40716389253;42288.15423691532;42329;42369.15261694764;38640.22719545607;38737;38856.22287554247;38999.61000389995;39110.413343799795;6922.619255940934;7019.298070192991;7033.120859892513;7091.361777440029;7736.922630773676;7765.689372425124;7872.370210383156;7882.566458844762;7887.881681774749;7896.684132634688;7873;7929.563873986928;7935.246151615616;7938.126806051329;7970.561619110947;7865.2134786521465;7895.328897043701;7913.050433947963;7914.406419518502;7917.56453395063;7771.611419429062;7775.533467991939;7824.765257042298;7831.334336581397;7832.255935686101;37969.0507737306;38148.42777358342;38166.61833381659;38443.61556384428;38457.61542384568;3492.406290930548;3511.841967111486;3527.805970671612;3546.4680297955515;3552.573691157052;30647.846760766275;30653.540196897065;30671.38657226843;30766.53850192249;30959.690403095905;5284.524392804666;5316.6544174628525;5324.653897496689;5325.760340784674;5363.678179309254;182140;182923;182965;182970;183520.0823995885;42414.57585424106;48463.51536484661;48477.27284090741;48677.51322486801;48693.51306486961;45460.77269613631;50548;51115;51296.48703512992;51353.229701554505;15038.624034399227;15156.848431515764;15167;15170.469033583771;15176.544703658908;12226.20529665569;12372.938135309267;12387.318697471726;12396.690082748004;12406.689832754253;13352.732945341038;13491.52779652727;13568.389422476012;13588.048836581343;13713.382897769625;5588.776448942078;5618.494335509824;5620.971895140498;5649.180868774005;5672.12082127271;996.7209181429212;1009.2733232073042;1020.6274709731043;1034.1519953637944;1050.1021626509225;23018;23062.884685576464;23247.651285230735;23266.883665581903;23297.534049319256;1105.6793529876406;1113.1706878375662;1117.932924024561;1123.213750374741;1130.4912789244872;12052.578159764365;12187.817182742267;12211;12365.876341236473;12417.813732794015;210063;210106;210443;211442;211732;52415.73792131091;52504.73747631237;52602.210966835526;52707.472925271024;52907.470925290254;1415.2074838090703;1473.5063753642435;1484.3839806480405;1484.46559238674;1486.2197346393014;22159.0028448717;22368.77631223667;22484.887575562672;22626.547469050198;23133.190338338078;36305.27389452144;36393.454098189075;36781.816090920445;36802.26395472022;36901;184555;185316;186094;186335;186513;30632.234194144432;31612.683873160975;32024.35951281008;32031.839840800647;32176.678233217368;41554.37668435036;41936.370954436316;41941;42064.579354206064;42551;31419.84290078535;31533.8423307882;31560.842195788875;31818.6818131825;31883.521747174276;31404;31458.37083258276;31513;31558.684413155574;31589.52615710812;34848.30303393969;35435.82282088573;35437.82281088578;35695.64304356923;35843.46234806428;29921;30386.54420183743;30407.391852163277;30569.54145687772;31289.68710312959;22699;22798.31605051851;22863.085476581415;22893.88553057224;23293.88353058224 21:20:01 INFO - PID 12725 | |2;jquery.html;551.7985935133676;566.7562947932388;569.8205065404395;570.9914351284734;586.4165155670107;22153.66769498459;22721.20475783348;22842.657360139612;22864.542709145808;22870.199543016;23110.65334019991;24389.39026524338;24443.511129777395;24753;24763;6471.44992675622;6660.433863121616;6693;6694.397504224618;6726.562773419737;6606.47148228143;6637.73449062037;6671.399574038335;6709.597424154542;6712.697928593225;6272.498200144;6508.609483430985;6549.34506549346;6574.605523668572;6631;6555.475561955056;6590.703418346186;6595.406413422791;6610.471162307028;6619.304972977816;5899.734511946973;6089.786857459968;6115.9694201529155;6117.480014198754;6140.017597184474;28112;28782;28808.855955720297;29110.12669619915;29239.2690182745;2100.810927016576;2122.1193204819983;2125.7342832145982;2132.5628246209644;2135.135270215565;18978.408636545355;19084.427467176007;19219.807801921943;19295.903520482447;19304.03479826017;2439.9390015249583;2443.853368797878;2514.459391230894;2517.546841568517;2524.3815265259946;119985.40007299995;126709;127382;136520;144332.99999999895;14505.42941757157;14699.750521205817;14746;14944.15431898262;15044.849551504563;22985;23228.070877164722;23236.767632323797;23376;23404.297871063896;9797.216222702167;9823.361481503822;9838.75403114928;9860.964598717032;9862.063104005108;8841.115888411236;8987.685431009882;9110.908890911138;9135;9214.723558293263;8775.210231079234;8939.821203575892;8954.955225223832;9083.273338132933;9136.350458366998;2527.987360063188;2535.1253817432957;2558.1941688368106;2560.5775047117017;2630.447606002761;934.6027938126405;965.2085290062222;969.4813274897896;982.3761911186448;988.461288597716;18769.34307299238;18894;19222.580388411425;19633.901830490755;19713.211471541264;1017.6031347774374;1019.0980981831133;1021.6628512590827;1033.844923261517;1038.0864838941834;10457.42484163363;10465.267431279737;10654.360738355723;10933.890661093446;11000.614978475875;417427;419298;435583;438165;443222;111949;114936.42531787287;116613;116948;117307;942.330945029037;999.77028255245;1028.0850043461326;1048.4338457233162;1148.2306854407566;18932.621347573247;19906.90046549758;19935.900320498306;20256.79743202549;20284.188632454538;54303.72848135892;56358.15462768144;56584.15123773229;56812.14781778194;57203.71398142983;68007;69321;69372.30627693658;69597.65201173961;70740.64629676819;14286;14302.856971430152;14346.139231646135;14434.278286085444;14484.493042743454;22366.77633223712;22369;22554.66168007514;22598.887005564866;22603.208887688852;17857.642847143245;17960.910195448938;18034.81965180384;18333.724994124834;18356.908215458836;16051.277692503632;16521.504354869372;16871.66256674884;16980.320787168373;17097.401590944257;18399;18531.536711582317;18631.975241361313;18679.1594378256;18701.15844787015;13476.393562289524;14321.713565728418;14336.426542938165;14358.928205359325;14374.928125359307;17935.910320448314;18073.36743213981;18108.818911810715;18131.728024079912;18134.45596632103 21:20:01 INFO - PID 12725 | |3;mootools.html;1323.4375390459054;1428.0360756489374;1438.8705016548508;1440.5318271561746;1450.7823826426036;14614.123152610837;15024.849751502481;15073.246337683113;15336.923315383407;15395.076295422283;14888.255587220623;14942;14996.175210363426;15034.84965150351;15085.170315632637;2685.9597106043425;2741.5476446386324;2754.7796176305847;2772.5979732938813;2772.8336299821976;2618.842869427831;2642.735726427361;2659.654244948154;2665.453582015682;2670.212287375229;2721.2788611018095;2742.2184677366954;2751.545994910837;2759.530879750447;2763.5301998660175;2642.1016854269546;2689.1932420274034;2704.445588654321;2713.74219449152;2714.484247992875;2684.3825920038503;2685.4494828560296;2688.2204160793344;2702.3649442381025;2704.864756762174;15059.548213553611;15068.773968390484;15101.3204405802;15103.093814371174;15230.771538426932;7829.960850195713;8007.958965334527;8039.316658084012;8051.355891528664;8055.718049868285;5502.339719233718;5667.744951477152;5712.914306285409;5777.913331300034;5822.650640961556;1854.4158590044092;1885.679434496139;1890.6974884018491;1927.3061697788785;1937.2444746548858;21990.670139947913;22748.88625556878;25352.746472535222;26545.20364389052;26685.19944401671;6867.862642747217;6940.30596940312;7059.399951004222;7146.499745017799;7159;16983.150842457828;17043.403480878358;17056.57358566046;17058.147092645566;17058.31766729317;7796.9220307796195;7971.481853679492;7974.082980457206;8029.397795165269;8032.959835200787;6966.442684585221;7351.926480735231;7365.300296471826;7395.926040739524;7409.851802964018;5333.386660534011;5386.973065134649;5462.945370546244;5464.28964234647;5485.369182543979;1522.3225664579156;1545.6754081642755;1558.5168597734512;1565.8512441318057;1578.936842526309;898.0929261445987;921.9170274675312;932.6782260120248;934.0332755598059;937.6202637931649;7491.363234125051;7522.887156692654;7666.271704188205;7716.7684969451;7741.535507869546;694.1427337238506;694.8019814352886;722.3607107709714;724.221461928429;735.400648471494;4387.561243875651;4414.050979039458;4484;4516.23224051912;4531.750753708512;20799.584008319747;21232.150713971576;21541.67687484689;21546.892265538572;21874.45313867166;20854.687179692315;21433.356999290045;21456.06895379392;21566.676499852514;21719.782802172092;10792.676219713421;10926.945365273281;10988.230823842254;10995.670129896116;11071.726751423528;7904.604769761604;8030.478018928751;8036.718714844952;8054.3556515479795;8075;18379.172937218125;18692.97188654637;18857.15142818604;18858.33995810195;19352.516187095436;18732.157052932922;18756.624867502847;19183;19209.423717288504;19558;10976.890231097586;11169.664910052712;11322.37726925027;11327.093832493216;11335.773284534429;13671.316434178052;13689.794653080411;13702.040857139904;13726.931365343109;13732.588022359345;10661.466926653791;11119.44402779874;11144.387058711525;11233;11392.772144556897;11113.722156946142;11429.314241145557;11483.827742584035;11517;11640.301581905112;10192.643257485952;10197.847032294374;10317.845232321672;10319.122874555447;10322.896771032194;7025.227225005143;7027.648617569203;7060.364567188978;7195.064641596559;7201.631689978887;6501.934980650322;6604.702788374439;6606.405423511907;6615.6030638161865;6697.732090716317 21:20:01 INFO - PID 12725 | |4;prototype.html;494.9046876873277;529.5869222006834;531.8670332416896;532.0709494234239;537.3524902492497;6137.631742095477;6201.689915504224;6286.3713628637115;6304.33804450533;6331.11364408982;6236.282827474842;6253.968730156343;6263.592866463688;6269.561130720851;6300.590461619991;5182.455842136578;5195.558377537914;5196.92204616931;5206.140986737184;5211.661242019276;5207.85427206016;5216.139337009407;5219.42586315505;5222.529972302492;5252.3171987641745;4781.426228852525;4956.801727930879;4971.1549036663855;4983.377077865267;5050.242463630448;4897.33885925399;4943.220440641367;4957.900841983176;4975.701457912519;5004.949950500485;4425.601695847389;4456.821727130943;4486.7083639563325;4497.347884556753;4525.343825145335;5815.651060936358;6016.398360163948;6047.792555717132;6059.242594675666;6112.471882029493;5589.916151257735;5605.887882242373;5658;5681;5700.458456446672;6383.457406120486;6436.839079023015;6546.574472659262;6547.315600603777;6585.982550644103;4843.152448321565;4860.829870954535;4872.975635121802;4882.483702575104;4899.093667671492;6518.608883467007;6652.201735791639;6678.398944095006;6695.430888374494;6772.11962444879;4979.651424400257;4984.7258400788305;4988.800447982113;4998.275250088716;5015.598752099823;5011.373578302712;5014.648974571745;5023.547880690755;5029.974850125726;5053.140966035783;5581.4976652100695;5623.522000629992;5626.549876009909;5637.7181140942785;5660;6433.420992110732;6446.613203207822;6508.414242718178;6550.508711846535;6604.5046621502615;1730.1781653714636;1740.9738853917202;1748.7901451825862;1757.1741281597629;1764.4706588023541;902.9187373136318;1206.9215500992407;1208.8307636930838;1222.2422098299112;1240.8262843201958;5646.237757902631;5674.290713660793;5726.169705392695;5775.989201889653;5788.739506722208;6568.277489476154;6572.488327684659;6590.373914478117;6611.20665520131;6643.833904152339;5707.4577915098;5717.199592057115;5733;5770.2498675171955;5775.913361299584;3531.417316142859;3547.255076433926;3549.9290014200087;3563.893083207508;3569;6271.7804876830005;6417.9679101605125;6425.293217746044;6501.772437964649;6543.738250470023;6698.626781509779;6767.830804229835;6775.356341147583;6791.762288319885;6797.504548999213;6326;6471.514636402195;6491.156149700509;6516.250631177475;6541.6402097885075;6549;6570.8028759137305;6621.50338724588;6636.170478690164;6697.06241126233;6539.051837483634;6563.835904102245;6584.604923704593;6591.34086591337;6627.668616569249;5854.970725146347;6338.90491642635;6355.428011478989;6452.677366131581;6495.480361570969;4854.271859221145;4935.901281974268;4965.900681986268;4971.65198436106;5002.649814512949;6250;6342.619442833445;6549.219070078964;6551.8206722790355;6613.570117942319;6157.846053848511;6262.405071518198;6315.652639104894;6338.524610654127;6393.7122829471855;6399.104125422537;6413.647249401141;6459.515536334888;6470.191226096738;6480;6312;6313.842153946188;6331.525135614939;6451.580647257913;6463.870722585616;6388.8083357499345;6390.201224846895;6397.8720425592155;6432;6495.577787443801;6201.224846894138;6222.315545289924;6300.2754683212015;6304.180456540804;6321.431071203614 21:20:01 INFO - PID 12725 | |5;yui.html;912.3112050401946;912.7535565397338;927.248928368022;929.4934260827848;950.790826018276;16802.91598542007;17336.91331543342;17373.652526949485;17382.478525644234;17454.738178927295;16837.073960932143;17130.82869171308;17477.650446991054;17558;17903.820961790378;922.1516205091299;936.3258453913172;938.5260443476035;941.8116376724648;944.6693657219987;965.5268918230087;968.7674958010098;970.7961328121103;978.2418625565173;981.7349315684761;960.5293406230967;1013.6148263659799;1020.5815615597569;1024.707958231904;1031.8968103189695;1001.2190491416707;1001.9949900250489;1002.7142264454632;1004.5479534209562;1005.2460654509118;708.5890183693446;720.0890873045549;721.6716394040699;722.3390597603187;724.337231433238;17703.29186832525;17773.377931772327;17781.644367112585;17841.910790446094;17849.37527186542;20713.58572828535;20938.581228375348;21113.894430527747;21444.89277553618;21895.890520547295;16154.676906461922;16289.755653665205;16370.427035053835;16504.587385315343;16618.169091545373;3735.4957080794015;3766.679832214265;3770.2836461072307;3783.6405541473514;3788.5453745550712;135405;138584;139242.3037884804;141532;142860.28569857188;14365.856341436453;15150.16674082914;15555.066695998275;15598.298076586581;15616.21918904073;30183.245418864703;31094.22264443407;31226.219344516114;31271.530927036114;31274.218144546114;8313.836062951192;8389.874151887727;8399.95800020996;8402.159784021549;8427.747167584983;4257.723247988871;4379.912401752011;4388.5830846069575;4411.669124815651;4414.735115892992;5297.073012222922;5345.438728933484;5356.143017117321;5398.271233383443;5429.375621803543;944.8535477001076;947.8672985781996;964.3394274921561;976.4336684722844;980.7057882635181;669.5012215899186;674.6592970549939;682.7166725808833;699.2203692882501;705.3722187253348;10403;10560;10657.62698305571;10932.453377331261;11187.552497899993;675.7769935921134;680.0818894492396;692.62944324786;694.4375056204491;708.5075872268827;5068.417132029864;5094.974525127351;5187.740612969336;5242.7378631068295;5286.022085914118;115173.4241328788;120574.39712801555;121339.39330303468;122112;124844;105568;107701.46149269203;108675;111517.99999999837;111532;91975;93073.60389594162;93896;95658.52170739102;95736.52131739297;9871.901280987386;9901.405915645084;9936.304458688111;9941.900580994386;9980.201583873128;40094;40934.795326024374;41158.588414115475;41825.95435114147;42043.57956420397;54411.72794136163;56503.15245271242;56523.43476565346;56607.71696141493;56684;15417.845821541641;15431.382744690087;15498.690026199187;15596;15694.2937567812;16639.916800415922;16716.58208544796;16789.74815377746;16933.915330423268;17134.657306854042;13121;13265.535706250235;13460;13537.526186583422;13660.590182294547;12949.964002879935;12983.870161298266;12998;13085.41115649779;13145.73708525805;12774.48902043945;12890.871091288967;12916.806247906477;12954.546590869275;13059.869401305865;10080.495975201356;10136.290459668047;10270.435126068138;10418.114460270961;10429.426381549089;9862.753431163992;9892.208623310262;9951.502424878583;9990.350627209207;10217.33587316822 21:20:01 INFO - PID 12725 | __end_tp_report 21:20:01 INFO - PID 12725 | __start_cc_report 21:20:01 INFO - PID 12725 | _x_x_mozilla_cycle_collect,284 21:20:01 INFO - PID 12725 | __end_cc_report 21:20:01 INFO - PID 12725 | __startTimestamp1505622001510__endTimestamp 21:20:01 INFO - PID 12725 | 21:20:01 INFO - PID 12725 | ------- Summary: start ------- 21:20:01 INFO - PID 12725 | Number of tests: 6 21:20:01 INFO - PID 12725 | 21:20:01 INFO - PID 12725 | [#0] dojo.html Cycles:165 Average:6161.19 Median:6373.52 stddev:727.87 (11.4%) stddev-sans-first:690.04 21:20:01 INFO - PID 12725 | Values: 3125.5 3141.0 3148.3 3185.2 3188.6 6150.7 6196.2 6276.9 6280.4 6315.4 6155.9 6200.8 6255.7 6259.2 6266.0 5701.3 5732.1 5734.5 5771.1 5908.4 5754.3 5814.9 5822.0 5868.3 5869.9 5747.7 5825.7 5831.1 5894.3 5906.6 5564.2 5696.4 5749.6 5763.6 5794.4 5701.3 5739.6 5773.4 5792.6 5825.9 6022.2 6155.2 6181.9 6355.4 6373.0 6353.4 6390.3 6418.1 6444.7 6453.5 6683.4 6770.4 6772.0 6789.0 6804.0 6291.3 6326.7 6353.3 6363.1 6370.6 6865.9 6927.3 7000.3 7027.7 7040.0 6423.8 6458.2 6487.2 6507.3 6646.6 6574.0 6633.9 6650.6 6652.3 6654.3 6556.1 6577.9 6605.6 6607.7 6612.7 6729.6 6756.2 6789.0 6814.0 6841.4 5283.3 5312.5 5356.5 5359.5 5360.2 4505.5 4581.4 4603.8 4644.6 4648.6 5703.0 5783.0 5794.8 5797.1 5894.0 6627.3 6699.1 6715.2 6757.8 6764.1 5821.9 5827.7 5864.9 5865.8 5887.3 5761.7 5773.0 5843.0 5856.3 5856.6 6392.3 6467.7 6478.5 6479.0 6482.6 6556.6 6601.6 6603.2 6631.8 6648.5 6556.4 6589.9 6640.4 6656.8 6692.2 6555.7 6643.5 6682.3 6729.2 6769.7 6724.8 6746.7 6841.6 6843.9 6845.6 6373.5 6377.9 6433.2 6479.7 6555.6 6497.8 6505.2 6542.2 6551.7 6584.5 6352.7 6510.1 6512.9 6525.8 6542.0 6514.2 6570.0 6584.9 6651.8 6675.3 6121.7 6147.6 6229.0 6248.9 6259.0 21:20:01 INFO - PID 12725 | 21:20:01 INFO - PID 12725 | [#1] ext.html Cycles:180 Average:36032.30 Median:3502.12 stddev:50062.31 (1429.5%) stddev-sans-first:50132.65 21:20:01 INFO - PID 12725 | Values: 750.5 771.5 772.7 781.4 785.1 38777.4 39522.4 42288.2 42329.0 42369.2 38640.2 38737.0 38856.2 38999.6 39110.4 6922.6 7019.3 7033.1 7091.4 7736.9 7765.7 7872.4 7882.6 7887.9 7896.7 7873.0 7929.6 7935.2 7938.1 7970.6 7865.2 7895.3 7913.1 7914.4 7917.6 7771.6 7775.5 7824.8 7831.3 7832.3 37969.1 38148.4 38166.6 38443.6 38457.6 3492.4 3511.8 3527.8 3546.5 3552.6 30647.8 30653.5 30671.4 30766.5 30959.7 5284.5 5316.7 5324.7 5325.8 5363.7 182140.0 182923.0 182965.0 182970.0 183520.1 42414.6 48463.5 48477.3 48677.5 48693.5 45460.8 50548.0 51115.0 51296.5 51353.2 15038.6 15156.8 15167.0 15170.5 15176.5 12226.2 12372.9 12387.3 12396.7 12406.7 13352.7 13491.5 13568.4 13588.0 13713.4 5588.8 5618.5 5621.0 5649.2 5672.1 996.7 1009.3 1020.6 1034.2 1050.1 23018.0 23062.9 23247.7 23266.9 23297.5 1105.7 1113.2 1117.9 1123.2 1130.5 12052.6 12187.8 12211.0 12365.9 12417.8 210063.0 210106.0 210443.0 211442.0 211732.0 52415.7 52504.7 52602.2 52707.5 52907.5 1415.2 1473.5 1484.4 1484.5 1486.2 22159.0 22368.8 22484.9 22626.5 23133.2 36305.3 36393.5 36781.8 36802.3 36901.0 184555.0 185316.0 186094.0 186335.0 186513.0 30632.2 31612.7 32024.4 32031.8 32176.7 41554.4 41936.4 41941.0 42064.6 42551.0 31419.8 31533.8 31560.8 31818.7 31883.5 31404.0 31458.4 31513.0 31558.7 31589.5 34848.3 35435.8 35437.8 35695.6 35843.5 29921.0 30386.5 30407.4 30569.5 31289.7 22699.0 22798.3 22863.1 22893.9 23293.9 21:20:01 INFO - PID 12725 | 21:20:01 INFO - PID 12725 | [#2] jquery.html Cycles:180 Average:32761.29 Median:23232.42 stddev:73271.86 (315.4%) stddev-sans-first:73437.49 21:20:01 INFO - PID 12725 | Values: 551.8 566.8 569.8 571.0 586.4 22153.7 22721.2 22842.7 22864.5 22870.2 23110.7 24389.4 24443.5 24753.0 24763.0 6471.4 6660.4 6693.0 6694.4 6726.6 6606.5 6637.7 6671.4 6709.6 6712.7 6272.5 6508.6 6549.3 6574.6 6631.0 6555.5 6590.7 6595.4 6610.5 6619.3 5899.7 6089.8 6116.0 6117.5 6140.0 28112.0 28782.0 28808.9 29110.1 29239.3 2100.8 2122.1 2125.7 2132.6 2135.1 18978.4 19084.4 19219.8 19295.9 19304.0 2439.9 2443.9 2514.5 2517.5 2524.4 119985.4 126709.0 127382.0 136520.0 144333.0 14505.4 14699.8 14746.0 14944.2 15044.8 22985.0 23228.1 23236.8 23376.0 23404.3 9797.2 9823.4 9838.8 9861.0 9862.1 8841.1 8987.7 9110.9 9135.0 9214.7 8775.2 8939.8 8955.0 9083.3 9136.4 2528.0 2535.1 2558.2 2560.6 2630.4 934.6 965.2 969.5 982.4 988.5 18769.3 18894.0 19222.6 19633.9 19713.2 1017.6 1019.1 1021.7 1033.8 1038.1 10457.4 10465.3 10654.4 10933.9 11000.6 417427.0 419298.0 435583.0 438165.0 443222.0 111949.0 114936.4 116613.0 116948.0 117307.0 942.3 999.8 1028.1 1048.4 1148.2 18932.6 19906.9 19935.9 20256.8 20284.2 54303.7 56358.2 56584.2 56812.1 57203.7 68007.0 69321.0 69372.3 69597.7 70740.6 14286.0 14302.9 14346.1 14434.3 14484.5 22366.8 22369.0 22554.7 22598.9 22603.2 17857.6 17960.9 18034.8 18333.7 18356.9 16051.3 16521.5 16871.7 16980.3 17097.4 18399.0 18531.5 18632.0 18679.2 18701.2 13476.4 14321.7 14336.4 14358.9 14374.9 17935.9 18073.4 18108.8 18131.7 18134.5 21:20:01 INFO - PID 12725 | 21:20:01 INFO - PID 12725 | [#3] mootools.html Cycles:180 Average:9210.95 Median:14593.97 stddev:6543.20 (44.8%) stddev-sans-first:6534.71 21:20:01 INFO - PID 12725 | Values: 1323.4 1428.0 1438.9 1440.5 1450.8 14614.1 15024.8 15073.2 15336.9 15395.1 14888.3 14942.0 14996.2 15034.8 15085.2 2686.0 2741.5 2754.8 2772.6 2772.8 2618.8 2642.7 2659.7 2665.5 2670.2 2721.3 2742.2 2751.5 2759.5 2763.5 2642.1 2689.2 2704.4 2713.7 2714.5 2684.4 2685.4 2688.2 2702.4 2704.9 15059.5 15068.8 15101.3 15103.1 15230.8 7830.0 8008.0 8039.3 8051.4 8055.7 5502.3 5667.7 5712.9 5777.9 5822.7 1854.4 1885.7 1890.7 1927.3 1937.2 21990.7 22748.9 25352.7 26545.2 26685.2 6867.9 6940.3 7059.4 7146.5 7159.0 16983.2 17043.4 17056.6 17058.1 17058.3 7796.9 7971.5 7974.1 8029.4 8033.0 6966.4 7351.9 7365.3 7395.9 7409.9 5333.4 5387.0 5462.9 5464.3 5485.4 1522.3 1545.7 1558.5 1565.9 1578.9 898.1 921.9 932.7 934.0 937.6 7491.4 7522.9 7666.3 7716.8 7741.5 694.1 694.8 722.4 724.2 735.4 4387.6 4414.1 4484.0 4516.2 4531.8 20799.6 21232.2 21541.7 21546.9 21874.5 20854.7 21433.4 21456.1 21566.7 21719.8 10792.7 10926.9 10988.2 10995.7 11071.7 7904.6 8030.5 8036.7 8054.4 8075.0 18379.2 18693.0 18857.2 18858.3 19352.5 18732.2 18756.6 19183.0 19209.4 19558.0 10976.9 11169.7 11322.4 11327.1 11335.8 13671.3 13689.8 13702.0 13726.9 13732.6 10661.5 11119.4 11144.4 11233.0 11392.8 11113.7 11429.3 11483.8 11517.0 11640.3 10192.6 10197.8 10317.8 10319.1 10322.9 7025.2 7027.6 7060.4 7195.1 7201.6 6501.9 6604.7 6606.4 6615.6 6697.7 21:20:01 INFO - PID 12725 | 21:20:01 INFO - PID 12725 | [#4] prototype.html Cycles:180 Average:5460.71 Median:6147.74 stddev:1512.03 (24.6%) stddev-sans-first:1469.62 21:20:01 INFO - PID 12725 | Values: 494.9 529.6 531.9 532.1 537.4 6137.6 6201.7 6286.4 6304.3 6331.1 6236.3 6254.0 6263.6 6269.6 6300.6 5182.5 5195.6 5196.9 5206.1 5211.7 5207.9 5216.1 5219.4 5222.5 5252.3 4781.4 4956.8 4971.2 4983.4 5050.2 4897.3 4943.2 4957.9 4975.7 5004.9 4425.6 4456.8 4486.7 4497.3 4525.3 5815.7 6016.4 6047.8 6059.2 6112.5 5589.9 5605.9 5658.0 5681.0 5700.5 6383.5 6436.8 6546.6 6547.3 6586.0 4843.2 4860.8 4873.0 4882.5 4899.1 6518.6 6652.2 6678.4 6695.4 6772.1 4979.7 4984.7 4988.8 4998.3 5015.6 5011.4 5014.6 5023.5 5030.0 5053.1 5581.5 5623.5 5626.5 5637.7 5660.0 6433.4 6446.6 6508.4 6550.5 6604.5 1730.2 1741.0 1748.8 1757.2 1764.5 902.9 1206.9 1208.8 1222.2 1240.8 5646.2 5674.3 5726.2 5776.0 5788.7 6568.3 6572.5 6590.4 6611.2 6643.8 5707.5 5717.2 5733.0 5770.2 5775.9 3531.4 3547.3 3549.9 3563.9 3569.0 6271.8 6418.0 6425.3 6501.8 6543.7 6698.6 6767.8 6775.4 6791.8 6797.5 6326.0 6471.5 6491.2 6516.3 6541.6 6549.0 6570.8 6621.5 6636.2 6697.1 6539.1 6563.8 6584.6 6591.3 6627.7 5855.0 6338.9 6355.4 6452.7 6495.5 4854.3 4935.9 4965.9 4971.7 5002.6 6250.0 6342.6 6549.2 6551.8 6613.6 6157.8 6262.4 6315.7 6338.5 6393.7 6399.1 6413.6 6459.5 6470.2 6480.0 6312.0 6313.8 6331.5 6451.6 6463.9 6388.8 6390.2 6397.9 6432.0 6495.6 6201.2 6222.3 6300.3 6304.2 6321.4 21:20:01 INFO - PID 12725 | 21:20:01 INFO - PID 12725 | [#5] yui.html Cycles:180 Average:23464.07 Median:30638.73 stddev:35179.16 (114.8%) stddev-sans-first:35237.10 21:20:01 INFO - PID 12725 | Values: 912.3 912.8 927.2 929.5 950.8 16802.9 17336.9 17373.7 17382.5 17454.7 16837.1 17130.8 17477.7 17558.0 17903.8 922.2 936.3 938.5 941.8 944.7 965.5 968.8 970.8 978.2 981.7 960.5 1013.6 1020.6 1024.7 1031.9 1001.2 1002.0 1002.7 1004.5 1005.2 708.6 720.1 721.7 722.3 724.3 17703.3 17773.4 17781.6 17841.9 17849.4 20713.6 20938.6 21113.9 21444.9 21895.9 16154.7 16289.8 16370.4 16504.6 16618.2 3735.5 3766.7 3770.3 3783.6 3788.5 135405.0 138584.0 139242.3 141532.0 142860.3 14365.9 15150.2 15555.1 15598.3 15616.2 30183.2 31094.2 31226.2 31271.5 31274.2 8313.8 8389.9 8400.0 8402.2 8427.7 4257.7 4379.9 4388.6 4411.7 4414.7 5297.1 5345.4 5356.1 5398.3 5429.4 944.9 947.9 964.3 976.4 980.7 669.5 674.7 682.7 699.2 705.4 10403.0 10560.0 10657.6 10932.5 11187.6 675.8 680.1 692.6 694.4 708.5 5068.4 5095.0 5187.7 5242.7 5286.0 115173.4 120574.4 121339.4 122112.0 124844.0 105568.0 107701.5 108675.0 111518.0 111532.0 91975.0 93073.6 93896.0 95658.5 95736.5 9871.9 9901.4 9936.3 9941.9 9980.2 40094.0 40934.8 41158.6 41826.0 42043.6 54411.7 56503.2 56523.4 56607.7 56684.0 15417.8 15431.4 15498.7 15596.0 15694.3 16639.9 16716.6 16789.7 16933.9 17134.7 13121.0 13265.5 13460.0 13537.5 13660.6 12950.0 12983.9 12998.0 13085.4 13145.7 12774.5 12890.9 12916.8 12954.5 13059.9 10080.5 10136.3 10270.4 10418.1 10429.4 9862.8 9892.2 9951.5 9990.4 10217.3 21:20:01 INFO - PID 12725 | -------- Summary: end -------- 21:20:01 INFO - PID 12725 | 21:20:02 INFO - TEST-INFO | 12725: exit 0 21:20:02 INFO - TEST-OK | dromaeo_css | took 1115073ms 21:20:02 INFO - TEST-START | kraken 21:20:02 INFO - Initialising browser for kraken test... 21:20:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51993/getInfo.html -profile /tmp/tmpKDfCn1/profile 21:20:02 INFO - TEST-INFO | started process 13106 (/builds/slave/test/build/application/firefox/firefox http://localhost:51993/getInfo.html) 21:20:08 INFO - TEST-INFO | 13106: exit 0 21:20:09 INFO - Browser initialized. 21:20:09 INFO - Running cycle 1/1 for kraken test... 21:20:09 INFO - Using env: {'DISPLAY': ':0', 21:20:09 INFO - 'HOME': '/home/cltbld', 21:20:09 INFO - 'JSGC_DISABLE_POISONING': '1', 21:20:09 INFO - 'LANG': 'en_US.UTF-8', 21:20:09 INFO - 'LANGUAGE': 'en_US:en', 21:20:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:20:09 INFO - 'LOGNAME': 'cltbld', 21:20:09 INFO - 'MAIL': '/var/mail/cltbld', 21:20:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:20:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:20:09 INFO - 'MOZ_ACCELERATED': '1', 21:20:09 INFO - 'MOZ_CRASHREPORTER': '1', 21:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:20:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:20:09 INFO - 'MOZ_NO_REMOTE': '1', 21:20:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:20:09 INFO - 'MOZ_WEBRENDER': '1', 21:20:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:20:09 INFO - 'NO_EM_RESTART': '1', 21:20:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:20:09 INFO - 'PWD': '/builds/slave/test', 21:20:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:20:09 INFO - 'RUST_BACKTRACE': 'full', 21:20:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:20:09 INFO - 'SHELL': '/bin/bash', 21:20:09 INFO - 'SHLVL': '1', 21:20:09 INFO - 'TERM': 'linux', 21:20:09 INFO - 'TMOUT': '86400', 21:20:09 INFO - 'USER': 'cltbld', 21:20:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119', 21:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:20:09 INFO - '_': '/tools/buildbot/bin/python'} 21:20:09 INFO - TEST-INFO | started process 13350 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKDfCn1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:20:09 INFO - PID 13350 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 21:20:10 INFO - PID 13350 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 21:20:10 INFO - PID 13350 | 21:20:10 INFO - PID 13350 | (/builds/slave/test/build/application/firefox/firefox:13410): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:20:10 INFO - PID 13350 | 21:20:20 INFO - PID 13350 | RSS: Main: 204914688 21:20:20 INFO - PID 13350 | 21:20:23 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51993/tests/kraken/driver.html?testName=audio-beat-detection) 21:20:24 INFO - PID 13350 | RSS: Main: 203202560 21:20:24 INFO - PID 13350 | 21:20:26 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51993/tests/kraken/driver.html?testName=audio-dft) 21:20:27 INFO - PID 13350 | RSS: Main: 203616256 21:20:27 INFO - PID 13350 | 21:20:30 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51993/tests/kraken/driver.html?testName=audio-fft) 21:20:31 INFO - PID 13350 | RSS: Main: 199712768 21:20:31 INFO - PID 13350 | 21:20:33 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51993/tests/kraken/driver.html?testName=audio-oscillator) 21:20:33 INFO - PID 13350 | RSS: Main: 200835072 21:20:33 INFO - PID 13350 | 21:20:36 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51993/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:20:36 INFO - PID 13350 | RSS: Main: 200175616 21:20:36 INFO - PID 13350 | 21:20:39 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51993/tests/kraken/driver.html?testName=imaging-darkroom) 21:20:40 INFO - PID 13350 | RSS: Main: 203173888 21:20:40 INFO - PID 13350 | 21:20:44 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51993/tests/kraken/driver.html?testName=imaging-desaturate) 21:20:44 INFO - PID 13350 | RSS: Main: 203128832 21:20:44 INFO - PID 13350 | 21:20:47 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51993/tests/kraken/driver.html?testName=json-parse-financial) 21:20:48 INFO - PID 13350 | RSS: Main: 203239424 21:20:48 INFO - PID 13350 | 21:20:50 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51993/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:20:50 INFO - PID 13350 | RSS: Main: 203743232 21:20:50 INFO - PID 13350 | 21:20:52 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51993/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:20:52 INFO - PID 13350 | RSS: Main: 203771904 21:20:52 INFO - PID 13350 | 21:20:55 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51993/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:20:55 INFO - PID 13350 | RSS: Main: 202969088 21:20:55 INFO - PID 13350 | 21:20:58 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51993/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:20:59 INFO - PID 13350 | RSS: Main: 203595776 21:20:59 INFO - PID 13350 | 21:21:01 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51993/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:21:02 INFO - PID 13350 | RSS: Main: 204173312 21:21:02 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | Cycle 1(1): loaded http://localhost:51993/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51993/tests/kraken/driver.html?testName=ai-astar) 21:21:04 INFO - PID 13350 | RSS: Main: 204664832 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | __start_tp_report 21:21:04 INFO - PID 13350 | _x_x_mozilla_page_load 21:21:04 INFO - PID 13350 | _x_x_mozilla_page_load_details 21:21:04 INFO - PID 13350 | |i|pagename|runs| 21:21:04 INFO - PID 13350 | |0;ai-astar;108;112;108;108;112;113;108;112;109;109 21:21:04 INFO - PID 13350 | |1;audio-beat-detection;124;122;116;124;153;119;125;120;155;117 21:21:04 INFO - PID 13350 | |2;audio-dft;168;169;171;164;170;175;168;164;166;166 21:21:04 INFO - PID 13350 | |3;audio-fft;81;77;74;82;84;78;85;80;74;74 21:21:04 INFO - PID 13350 | |4;audio-oscillator;86;80;87;88;80;80;85;83;87;89 21:21:04 INFO - PID 13350 | |5;imaging-gaussian-blur;114;114;112;114;115;115;114;119;119;116 21:21:04 INFO - PID 13350 | |6;imaging-darkroom;149;150;149;150;148;154;148;148;149;151 21:21:04 INFO - PID 13350 | |7;imaging-desaturate;100;103;95;94;94;95;98;95;94;95 21:21:04 INFO - PID 13350 | |8;json-parse-financial;55;55;54;55;53;55;57;62;56;56 21:21:04 INFO - PID 13350 | |9;json-stringify-tinderbox;43;43;45;45;38;38;38;44;47;37 21:21:04 INFO - PID 13350 | |10;stanford-crypto-aes;79;74;73;79;80;74;74;81;83;72 21:21:04 INFO - PID 13350 | |11;stanford-crypto-ccm;130;123;110;117;111;109;120;126;111;114 21:21:04 INFO - PID 13350 | |12;stanford-crypto-pbkdf2;173;166;174;170;169;164;168;173;165;167 21:21:04 INFO - PID 13350 | |13;stanford-crypto-sha256-iterative;70;78;65;65;64;64;66;65;63;68 21:21:04 INFO - PID 13350 | __end_tp_report 21:21:04 INFO - PID 13350 | __start_cc_report 21:21:04 INFO - PID 13350 | _x_x_mozilla_cycle_collect,752 21:21:04 INFO - PID 13350 | __end_cc_report 21:21:04 INFO - PID 13350 | __startTimestamp1505622064616__endTimestamp 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | ------- Summary: start ------- 21:21:04 INFO - PID 13350 | Number of tests: 14 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#0] ai-astar Cycles:10 Average:109.90 Median:110.50 stddev:2.08 (1.9%) stddev-sans-first:2.09 21:21:04 INFO - PID 13350 | Values: 108.0 112.0 108.0 108.0 112.0 113.0 108.0 112.0 109.0 109.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:124.00 stddev:14.29 (11.5%) stddev-sans-first:15.10 21:21:04 INFO - PID 13350 | Values: 124.0 122.0 116.0 124.0 153.0 119.0 125.0 120.0 155.0 117.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#2] audio-dft Cycles:10 Average:168.10 Median:168.50 stddev:3.38 (2.0%) stddev-sans-first:3.59 21:21:04 INFO - PID 13350 | Values: 168.0 169.0 171.0 164.0 170.0 175.0 168.0 164.0 166.0 166.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#3] audio-fft Cycles:10 Average:78.90 Median:80.50 stddev:4.15 (5.2%) stddev-sans-first:4.33 21:21:04 INFO - PID 13350 | Values: 81.0 77.0 74.0 82.0 84.0 78.0 85.0 80.0 74.0 74.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#4] audio-oscillator Cycles:10 Average:84.50 Median:86.50 stddev:3.50 (4.1%) stddev-sans-first:3.67 21:21:04 INFO - PID 13350 | Values: 86.0 80.0 87.0 88.0 80.0 80.0 85.0 83.0 87.0 89.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:115.00 stddev:2.25 (2.0%) stddev-sans-first:2.35 21:21:04 INFO - PID 13350 | Values: 114.0 114.0 112.0 114.0 115.0 115.0 114.0 119.0 119.0 116.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:149.50 stddev:1.84 (1.2%) stddev-sans-first:1.94 21:21:04 INFO - PID 13350 | Values: 149.0 150.0 149.0 150.0 148.0 154.0 148.0 148.0 149.0 151.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:95.00 stddev:3.06 (3.2%) stddev-sans-first:2.93 21:21:04 INFO - PID 13350 | Values: 100.0 103.0 95.0 94.0 94.0 95.0 98.0 95.0 94.0 95.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:55.50 stddev:2.44 (4.4%) stddev-sans-first:2.57 21:21:04 INFO - PID 13350 | Values: 55.0 55.0 54.0 55.0 53.0 55.0 57.0 62.0 56.0 56.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#9] json-stringify-tinderbox Cycles:10 Average:41.80 Median:43.50 stddev:3.68 (8.4%) stddev-sans-first:3.87 21:21:04 INFO - PID 13350 | Values: 43.0 43.0 45.0 45.0 38.0 38.0 38.0 44.0 47.0 37.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:79.00 stddev:3.90 (4.9%) stddev-sans-first:4.06 21:21:04 INFO - PID 13350 | Values: 79.0 74.0 73.0 79.0 80.0 74.0 74.0 81.0 83.0 72.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#11] stanford-crypto-ccm Cycles:10 Average:117.10 Median:118.50 stddev:7.37 (6.2%) stddev-sans-first:6.16 21:21:04 INFO - PID 13350 | Values: 130.0 123.0 110.0 117.0 111.0 109.0 120.0 126.0 111.0 114.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.50 stddev:3.54 (2.1%) stddev-sans-first:3.43 21:21:04 INFO - PID 13350 | Values: 173.0 166.0 174.0 170.0 169.0 164.0 168.0 173.0 165.0 167.0 21:21:04 INFO - PID 13350 | 21:21:04 INFO - PID 13350 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:65.50 stddev:4.44 (6.8%) stddev-sans-first:4.56 21:21:04 INFO - PID 13350 | Values: 70.0 78.0 65.0 65.0 64.0 64.0 66.0 65.0 63.0 68.0 21:21:04 INFO - PID 13350 | -------- Summary: end -------- 21:21:04 INFO - PID 13350 | 21:21:05 INFO - PID 13350 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:21:05 INFO - TEST-INFO | 13350: exit 0 21:21:05 INFO - TEST-OK | kraken | took 63081ms 21:21:05 INFO - SUITE-END | took 1178s 21:21:05 INFO - Completed test suite (00:19:38) 21:21:05 INFO - javascript benchmark 21:21:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8341.312168178923, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3125.5467957146216, 3140.9842950785255, 3148.338848841743, 3185.1718553176165, 3188.6173659160895, 6150.6924653767355, 6196.194494715686, 6276.937230627693, 6280.371962803717, 6315.40003699649, 6155.907661385072, 6200.813975580729, 6255.655938923357, 6259.155014073111, 6265.9686701566425, 5701.287339082614, 5732.082866741312, 5734.455226753452, 5771.134329850514, 5908.379620139888, 5754.251947246852, 5814.883702325972, 5822.039363505017, 5868.295804503444, 5869.85325366865, 5747.741351639186, 5825.737841797109, 5831.096180092092, 5894.322152952401, 5906.645601263916, 5564.1931919871395, 5696.3733989261145, 5749.568782341341, 5763.567732420043, 5794.449527294901, 5701.315842098933, 5739.569532285095, 5773.422657734235, 5792.623479473821, 5825.9417405825825, 6022.187004754346, 6155.199824022849, 6181.876362472771, 6355.428011478989, 6372.968135159341, 6353.4281914627445, 6390.329015453403, 6418.133551970471, 6444.677766111676, 6453.548251622388, 6683.365080317362, 6770.356816102462, 6771.966140169267, 6788.966055169742, 6804.047433359334, 6291.276503202191, 6326.68366581669, 6353.332900045475, 6363.140975968231, 6370.5540612157165, 6865.931340686529, 6927.3419025192525, 7000.26497217795, 7027.683754231072, 7040.0, 6423.775167869102, 6458.160439142881, 6487.18910136233, 6507.3167317431935, 6646.567973081735, 6573.967130164318, 6633.900491492632, 6650.567713098633, 6652.268250492442, 6654.301298363602, 6556.147700798961, 6577.901331480031, 6605.570637908617, 6607.735690572419, 6612.7354905804195, 6729.629870357079, 6756.155480564929, 6788.9660551696925, 6813.965930170318, 6841.3842754152365, 5283.339582552181, 5312.548433383128, 5356.517913387814, 5359.464053594609, 5360.249565060894, 4505.459344878635, 4581.404417425713, 4603.838865639686, 4644.558766917137, 4648.581627653527, 5702.971485142548, 5783.0, 5794.768209271666, 5797.072468405035, 5893.970530147408, 6627.3372662733345, 6699.095622091053, 6715.1941766988275, 6757.831054223586, 6764.120664313707, 5821.912671309934, 5827.650340979555, 5864.912026319608, 5865.794697185578, 5887.293524777054, 5761.711914404263, 5773.0, 5842.970785146047, 5856.267966504187, 5856.619319744203, 6392.296847346788, 6467.708953097122, 6478.481721462272, 6478.967605161944, 6482.611043337415, 6556.63938483388, 6601.603903765789, 6603.174603174603, 6631.834204144935, 6648.501362397937, 6556.442702370352, 6589.868202636017, 6640.435562977201, 6656.83357916056, 6692.196936367667, 6555.672216389066, 6643.468522518284, 6682.298358672269, 6729.192496900403, 6769.729210831511, 6724.7646332378135, 6746.696398661974, 6841.62371069596, 6843.863122737418, 6845.589264643938, 6373.521985850987, 6377.87244255103, 6433.195850518685, 6479.7408103675325, 6555.639439830855, 6497.805065848032, 6505.154329937078, 6542.182227221598, 6551.705173267119, 6584.506162037962, 6352.745890164527, 6510.121133646897, 6512.869742605216, 6525.771597994238, 6541.967290163519, 6514.218293804584, 6570.0, 6584.901226481511, 6651.800445986628, 6675.265720770809, 6121.663308518075, 6147.569670123048, 6228.968855155696, 6248.937510625017, 6259.0], "value": 6106.378642799052, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.4596690382924, 771.5023809642779, 772.7102336623766, 781.3905153979894, 785.140271402814, 38777.41833872491, 39522.40716389253, 42288.15423691532, 42329.0, 42369.15261694764, 38640.22719545607, 38737.0, 38856.22287554247, 38999.61000389995, 39110.413343799795, 6922.619255940934, 7019.298070192991, 7033.120859892513, 7091.361777440029, 7736.922630773676, 7765.689372425124, 7872.370210383156, 7882.566458844762, 7887.881681774749, 7896.684132634688, 7873.0, 7929.563873986928, 7935.246151615616, 7938.126806051329, 7970.561619110947, 7865.2134786521465, 7895.328897043701, 7913.050433947963, 7914.406419518502, 7917.56453395063, 7771.611419429062, 7775.533467991939, 7824.765257042298, 7831.334336581397, 7832.255935686101, 37969.0507737306, 38148.42777358342, 38166.61833381659, 38443.61556384428, 38457.61542384568, 3492.406290930548, 3511.841967111486, 3527.805970671612, 3546.4680297955515, 3552.573691157052, 30647.846760766275, 30653.540196897065, 30671.38657226843, 30766.53850192249, 30959.690403095905, 5284.524392804666, 5316.6544174628525, 5324.653897496689, 5325.760340784674, 5363.678179309254, 182140.0, 182923.0, 182965.0, 182970.0, 183520.0823995885, 42414.57585424106, 48463.51536484661, 48477.27284090741, 48677.51322486801, 48693.51306486961, 45460.77269613631, 50548.0, 51115.0, 51296.48703512992, 51353.229701554505, 15038.624034399227, 15156.848431515764, 15167.0, 15170.469033583771, 15176.544703658908, 12226.20529665569, 12372.938135309267, 12387.318697471726, 12396.690082748004, 12406.689832754253, 13352.732945341038, 13491.52779652727, 13568.389422476012, 13588.048836581343, 13713.382897769625, 5588.776448942078, 5618.494335509824, 5620.971895140498, 5649.180868774005, 5672.12082127271, 996.7209181429212, 1009.2733232073042, 1020.6274709731043, 1034.1519953637944, 1050.1021626509225, 23018.0, 23062.884685576464, 23247.651285230735, 23266.883665581903, 23297.534049319256, 1105.6793529876406, 1113.1706878375662, 1117.932924024561, 1123.213750374741, 1130.4912789244872, 12052.578159764365, 12187.817182742267, 12211.0, 12365.876341236473, 12417.813732794015, 210063.0, 210106.0, 210443.0, 211442.0, 211732.0, 52415.73792131091, 52504.73747631237, 52602.210966835526, 52707.472925271024, 52907.470925290254, 1415.2074838090703, 1473.5063753642435, 1484.3839806480405, 1484.46559238674, 1486.2197346393014, 22159.0028448717, 22368.77631223667, 22484.887575562672, 22626.547469050198, 23133.190338338078, 36305.27389452144, 36393.454098189075, 36781.816090920445, 36802.26395472022, 36901.0, 184555.0, 185316.0, 186094.0, 186335.0, 186513.0, 30632.234194144432, 31612.683873160975, 32024.35951281008, 32031.839840800647, 32176.678233217368, 41554.37668435036, 41936.370954436316, 41941.0, 42064.579354206064, 42551.0, 31419.84290078535, 31533.8423307882, 31560.842195788875, 31818.6818131825, 31883.521747174276, 31404.0, 31458.37083258276, 31513.0, 31558.684413155574, 31589.52615710812, 34848.30303393969, 35435.82282088573, 35437.82281088578, 35695.64304356923, 35843.46234806428, 29921.0, 30386.54420183743, 30407.391852163277, 30569.54145687772, 31289.68710312959, 22699.0, 22798.31605051851, 22863.085476581415, 22893.88553057224, 23293.88353058224], "value": 16780.170365533275, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.7985935133676, 566.7562947932388, 569.8205065404395, 570.9914351284734, 586.4165155670107, 22153.66769498459, 22721.20475783348, 22842.657360139612, 22864.542709145808, 22870.199543016, 23110.65334019991, 24389.39026524338, 24443.511129777395, 24753.0, 24763.0, 6471.44992675622, 6660.433863121616, 6693.0, 6694.397504224618, 6726.562773419737, 6606.47148228143, 6637.73449062037, 6671.399574038335, 6709.597424154542, 6712.697928593225, 6272.498200144, 6508.609483430985, 6549.34506549346, 6574.605523668572, 6631.0, 6555.475561955056, 6590.703418346186, 6595.406413422791, 6610.471162307028, 6619.304972977816, 5899.734511946973, 6089.786857459968, 6115.9694201529155, 6117.480014198754, 6140.017597184474, 28112.0, 28782.0, 28808.855955720297, 29110.12669619915, 29239.2690182745, 2100.810927016576, 2122.1193204819983, 2125.7342832145982, 2132.5628246209644, 2135.135270215565, 18978.408636545355, 19084.427467176007, 19219.807801921943, 19295.903520482447, 19304.03479826017, 2439.9390015249583, 2443.853368797878, 2514.459391230894, 2517.546841568517, 2524.3815265259946, 119985.40007299995, 126709.0, 127382.0, 136520.0, 144332.99999999895, 14505.42941757157, 14699.750521205817, 14746.0, 14944.15431898262, 15044.849551504563, 22985.0, 23228.070877164722, 23236.767632323797, 23376.0, 23404.297871063896, 9797.216222702167, 9823.361481503822, 9838.75403114928, 9860.964598717032, 9862.063104005108, 8841.115888411236, 8987.685431009882, 9110.908890911138, 9135.0, 9214.723558293263, 8775.210231079234, 8939.821203575892, 8954.955225223832, 9083.273338132933, 9136.350458366998, 2527.987360063188, 2535.1253817432957, 2558.1941688368106, 2560.5775047117017, 2630.447606002761, 934.6027938126405, 965.2085290062222, 969.4813274897896, 982.3761911186448, 988.461288597716, 18769.34307299238, 18894.0, 19222.580388411425, 19633.901830490755, 19713.211471541264, 1017.6031347774374, 1019.0980981831133, 1021.6628512590827, 1033.844923261517, 1038.0864838941834, 10457.42484163363, 10465.267431279737, 10654.360738355723, 10933.890661093446, 11000.614978475875, 417427.0, 419298.0, 435583.0, 438165.0, 443222.0, 111949.0, 114936.42531787287, 116613.0, 116948.0, 117307.0, 942.330945029037, 999.77028255245, 1028.0850043461326, 1048.4338457233162, 1148.2306854407566, 18932.621347573247, 19906.90046549758, 19935.900320498306, 20256.79743202549, 20284.188632454538, 54303.72848135892, 56358.15462768144, 56584.15123773229, 56812.14781778194, 57203.71398142983, 68007.0, 69321.0, 69372.30627693658, 69597.65201173961, 70740.64629676819, 14286.0, 14302.856971430152, 14346.139231646135, 14434.278286085444, 14484.493042743454, 22366.77633223712, 22369.0, 22554.66168007514, 22598.887005564866, 22603.208887688852, 17857.642847143245, 17960.910195448938, 18034.81965180384, 18333.724994124834, 18356.908215458836, 16051.277692503632, 16521.504354869372, 16871.66256674884, 16980.320787168373, 17097.401590944257, 18399.0, 18531.536711582317, 18631.975241361313, 18679.1594378256, 18701.15844787015, 13476.393562289524, 14321.713565728418, 14336.426542938165, 14358.928205359325, 14374.928125359307, 17935.910320448314, 18073.36743213981, 18108.818911810715, 18131.728024079912, 18134.45596632103], "value": 11787.870505504687, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.4375390459054, 1428.0360756489374, 1438.8705016548508, 1440.5318271561746, 1450.7823826426036, 14614.123152610837, 15024.849751502481, 15073.246337683113, 15336.923315383407, 15395.076295422283, 14888.255587220623, 14942.0, 14996.175210363426, 15034.84965150351, 15085.170315632637, 2685.9597106043425, 2741.5476446386324, 2754.7796176305847, 2772.5979732938813, 2772.8336299821976, 2618.842869427831, 2642.735726427361, 2659.654244948154, 2665.453582015682, 2670.212287375229, 2721.2788611018095, 2742.2184677366954, 2751.545994910837, 2759.530879750447, 2763.5301998660175, 2642.1016854269546, 2689.1932420274034, 2704.445588654321, 2713.74219449152, 2714.484247992875, 2684.3825920038503, 2685.4494828560296, 2688.2204160793344, 2702.3649442381025, 2704.864756762174, 15059.548213553611, 15068.773968390484, 15101.3204405802, 15103.093814371174, 15230.771538426932, 7829.960850195713, 8007.958965334527, 8039.316658084012, 8051.355891528664, 8055.718049868285, 5502.339719233718, 5667.744951477152, 5712.914306285409, 5777.913331300034, 5822.650640961556, 1854.4158590044092, 1885.679434496139, 1890.6974884018491, 1927.3061697788785, 1937.2444746548858, 21990.670139947913, 22748.88625556878, 25352.746472535222, 26545.20364389052, 26685.19944401671, 6867.862642747217, 6940.30596940312, 7059.399951004222, 7146.499745017799, 7159.0, 16983.150842457828, 17043.403480878358, 17056.57358566046, 17058.147092645566, 17058.31766729317, 7796.9220307796195, 7971.481853679492, 7974.082980457206, 8029.397795165269, 8032.959835200787, 6966.442684585221, 7351.926480735231, 7365.300296471826, 7395.926040739524, 7409.851802964018, 5333.386660534011, 5386.973065134649, 5462.945370546244, 5464.28964234647, 5485.369182543979, 1522.3225664579156, 1545.6754081642755, 1558.5168597734512, 1565.8512441318057, 1578.936842526309, 898.0929261445987, 921.9170274675312, 932.6782260120248, 934.0332755598059, 937.6202637931649, 7491.363234125051, 7522.887156692654, 7666.271704188205, 7716.7684969451, 7741.535507869546, 694.1427337238506, 694.8019814352886, 722.3607107709714, 724.221461928429, 735.400648471494, 4387.561243875651, 4414.050979039458, 4484.0, 4516.23224051912, 4531.750753708512, 20799.584008319747, 21232.150713971576, 21541.67687484689, 21546.892265538572, 21874.45313867166, 20854.687179692315, 21433.356999290045, 21456.06895379392, 21566.676499852514, 21719.782802172092, 10792.676219713421, 10926.945365273281, 10988.230823842254, 10995.670129896116, 11071.726751423528, 7904.604769761604, 8030.478018928751, 8036.718714844952, 8054.3556515479795, 8075.0, 18379.172937218125, 18692.97188654637, 18857.15142818604, 18858.33995810195, 19352.516187095436, 18732.157052932922, 18756.624867502847, 19183.0, 19209.423717288504, 19558.0, 10976.890231097586, 11169.664910052712, 11322.37726925027, 11327.093832493216, 11335.773284534429, 13671.316434178052, 13689.794653080411, 13702.040857139904, 13726.931365343109, 13732.588022359345, 10661.466926653791, 11119.44402779874, 11144.387058711525, 11233.0, 11392.772144556897, 11113.722156946142, 11429.314241145557, 11483.827742584035, 11517.0, 11640.301581905112, 10192.643257485952, 10197.847032294374, 10317.845232321672, 10319.122874555447, 10322.896771032194, 7025.227225005143, 7027.648617569203, 7060.364567188978, 7195.064641596559, 7201.631689978887, 6501.934980650322, 6604.702788374439, 6606.405423511907, 6615.6030638161865, 6697.732090716317], "value": 6570.085435926545, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.9046876873277, 529.5869222006834, 531.8670332416896, 532.0709494234239, 537.3524902492497, 6137.631742095477, 6201.689915504224, 6286.3713628637115, 6304.33804450533, 6331.11364408982, 6236.282827474842, 6253.968730156343, 6263.592866463688, 6269.561130720851, 6300.590461619991, 5182.455842136578, 5195.558377537914, 5196.92204616931, 5206.140986737184, 5211.661242019276, 5207.85427206016, 5216.139337009407, 5219.42586315505, 5222.529972302492, 5252.3171987641745, 4781.426228852525, 4956.801727930879, 4971.1549036663855, 4983.377077865267, 5050.242463630448, 4897.33885925399, 4943.220440641367, 4957.900841983176, 4975.701457912519, 5004.949950500485, 4425.601695847389, 4456.821727130943, 4486.7083639563325, 4497.347884556753, 4525.343825145335, 5815.651060936358, 6016.398360163948, 6047.792555717132, 6059.242594675666, 6112.471882029493, 5589.916151257735, 5605.887882242373, 5658.0, 5681.0, 5700.458456446672, 6383.457406120486, 6436.839079023015, 6546.574472659262, 6547.315600603777, 6585.982550644103, 4843.152448321565, 4860.829870954535, 4872.975635121802, 4882.483702575104, 4899.093667671492, 6518.608883467007, 6652.201735791639, 6678.398944095006, 6695.430888374494, 6772.11962444879, 4979.651424400257, 4984.7258400788305, 4988.800447982113, 4998.275250088716, 5015.598752099823, 5011.373578302712, 5014.648974571745, 5023.547880690755, 5029.974850125726, 5053.140966035783, 5581.4976652100695, 5623.522000629992, 5626.549876009909, 5637.7181140942785, 5660.0, 6433.420992110732, 6446.613203207822, 6508.414242718178, 6550.508711846535, 6604.5046621502615, 1730.1781653714636, 1740.9738853917202, 1748.7901451825862, 1757.1741281597629, 1764.4706588023541, 902.9187373136318, 1206.9215500992407, 1208.8307636930838, 1222.2422098299112, 1240.8262843201958, 5646.237757902631, 5674.290713660793, 5726.169705392695, 5775.989201889653, 5788.739506722208, 6568.277489476154, 6572.488327684659, 6590.373914478117, 6611.20665520131, 6643.833904152339, 5707.4577915098, 5717.199592057115, 5733.0, 5770.2498675171955, 5775.913361299584, 3531.417316142859, 3547.255076433926, 3549.9290014200087, 3563.893083207508, 3569.0, 6271.7804876830005, 6417.9679101605125, 6425.293217746044, 6501.772437964649, 6543.738250470023, 6698.626781509779, 6767.830804229835, 6775.356341147583, 6791.762288319885, 6797.504548999213, 6326.0, 6471.514636402195, 6491.156149700509, 6516.250631177475, 6541.6402097885075, 6549.0, 6570.8028759137305, 6621.50338724588, 6636.170478690164, 6697.06241126233, 6539.051837483634, 6563.835904102245, 6584.604923704593, 6591.34086591337, 6627.668616569249, 5854.970725146347, 6338.90491642635, 6355.428011478989, 6452.677366131581, 6495.480361570969, 4854.271859221145, 4935.901281974268, 4965.900681986268, 4971.65198436106, 5002.649814512949, 6250.0, 6342.619442833445, 6549.219070078964, 6551.8206722790355, 6613.570117942319, 6157.846053848511, 6262.405071518198, 6315.652639104894, 6338.524610654127, 6393.7122829471855, 6399.104125422537, 6413.647249401141, 6459.515536334888, 6470.191226096738, 6480.0, 6312.0, 6313.842153946188, 6331.525135614939, 6451.580647257913, 6463.870722585616, 6388.8083357499345, 6390.201224846895, 6397.8720425592155, 6432.0, 6495.577787443801, 6201.224846894138, 6222.315545289924, 6300.2754683212015, 6304.180456540804, 6321.431071203614], "value": 5014.69360597527, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [912.3112050401946, 912.7535565397338, 927.248928368022, 929.4934260827848, 950.790826018276, 16802.91598542007, 17336.91331543342, 17373.652526949485, 17382.478525644234, 17454.738178927295, 16837.073960932143, 17130.82869171308, 17477.650446991054, 17558.0, 17903.820961790378, 922.1516205091299, 936.3258453913172, 938.5260443476035, 941.8116376724648, 944.6693657219987, 965.5268918230087, 968.7674958010098, 970.7961328121103, 978.2418625565173, 981.7349315684761, 960.5293406230967, 1013.6148263659799, 1020.5815615597569, 1024.707958231904, 1031.8968103189695, 1001.2190491416707, 1001.9949900250489, 1002.7142264454632, 1004.5479534209562, 1005.2460654509118, 708.5890183693446, 720.0890873045549, 721.6716394040699, 722.3390597603187, 724.337231433238, 17703.29186832525, 17773.377931772327, 17781.644367112585, 17841.910790446094, 17849.37527186542, 20713.58572828535, 20938.581228375348, 21113.894430527747, 21444.89277553618, 21895.890520547295, 16154.676906461922, 16289.755653665205, 16370.427035053835, 16504.587385315343, 16618.169091545373, 3735.4957080794015, 3766.679832214265, 3770.2836461072307, 3783.6405541473514, 3788.5453745550712, 135405.0, 138584.0, 139242.3037884804, 141532.0, 142860.28569857188, 14365.856341436453, 15150.16674082914, 15555.066695998275, 15598.298076586581, 15616.21918904073, 30183.245418864703, 31094.22264443407, 31226.219344516114, 31271.530927036114, 31274.218144546114, 8313.836062951192, 8389.874151887727, 8399.95800020996, 8402.159784021549, 8427.747167584983, 4257.723247988871, 4379.912401752011, 4388.5830846069575, 4411.669124815651, 4414.735115892992, 5297.073012222922, 5345.438728933484, 5356.143017117321, 5398.271233383443, 5429.375621803543, 944.8535477001076, 947.8672985781996, 964.3394274921561, 976.4336684722844, 980.7057882635181, 669.5012215899186, 674.6592970549939, 682.7166725808833, 699.2203692882501, 705.3722187253348, 10403.0, 10560.0, 10657.62698305571, 10932.453377331261, 11187.552497899993, 675.7769935921134, 680.0818894492396, 692.62944324786, 694.4375056204491, 708.5075872268827, 5068.417132029864, 5094.974525127351, 5187.740612969336, 5242.7378631068295, 5286.022085914118, 115173.4241328788, 120574.39712801555, 121339.39330303468, 122112.0, 124844.0, 105568.0, 107701.46149269203, 108675.0, 111517.99999999837, 111532.0, 91975.0, 93073.60389594162, 93896.0, 95658.52170739102, 95736.52131739297, 9871.901280987386, 9901.405915645084, 9936.304458688111, 9941.900580994386, 9980.201583873128, 40094.0, 40934.795326024374, 41158.588414115475, 41825.95435114147, 42043.57956420397, 54411.72794136163, 56503.15245271242, 56523.43476565346, 56607.71696141493, 56684.0, 15417.845821541641, 15431.382744690087, 15498.690026199187, 15596.0, 15694.2937567812, 16639.916800415922, 16716.58208544796, 16789.74815377746, 16933.915330423268, 17134.657306854042, 13121.0, 13265.535706250235, 13460.0, 13537.526186583422, 13660.590182294547, 12949.964002879935, 12983.870161298266, 12998.0, 13085.41115649779, 13145.73708525805, 12774.48902043945, 12890.871091288967, 12916.806247906477, 12954.546590869275, 13059.869401305865, 10080.495975201356, 10136.290459668047, 10270.435126068138, 10418.114460270961, 10429.426381549089, 9862.753431163992, 9892.208623310262, 9951.502424878583, 9990.350627209207, 10217.33587316822], "value": 8463.49825709699, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1457.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 112.0, 108.0, 108.0, 112.0, 113.0, 108.0, 112.0, 109.0, 109.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 122.0, 116.0, 124.0, 153.0, 119.0, 125.0, 120.0, 155.0, 117.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 171.0, 164.0, 170.0, 175.0, 168.0, 164.0, 166.0, 166.0], "value": 168.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 77.0, 74.0, 82.0, 84.0, 78.0, 85.0, 80.0, 74.0, 74.0], "value": 78.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 87.0, 88.0, 80.0, 80.0, 85.0, 83.0, 87.0, 89.0], "value": 84.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 112.0, 114.0, 115.0, 115.0, 114.0, 119.0, 119.0, 116.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 150.0, 148.0, 154.0, 148.0, 148.0, 149.0, 151.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 103.0, 95.0, 94.0, 94.0, 95.0, 98.0, 95.0, 94.0, 95.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 55.0, 53.0, 55.0, 57.0, 62.0, 56.0, 56.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 45.0, 45.0, 38.0, 38.0, 38.0, 44.0, 47.0, 37.0], "value": 41.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 73.0, 79.0, 80.0, 74.0, 74.0, 81.0, 83.0, 72.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 123.0, 110.0, 117.0, 111.0, 109.0, 120.0, 126.0, 111.0, 114.0], "value": 117.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 166.0, 174.0, 170.0, 169.0, 164.0, 168.0, 173.0, 165.0, 167.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 65.0, 65.0, 64.0, 64.0, 66.0, 65.0, 63.0, 68.0], "value": 66.8, "unit": "score"}]}]} 21:21:05 INFO - Return code: 0 21:21:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:21:05 INFO - # TBPL SUCCESS # 21:21:05 INFO - Running post-action listener: _package_coverage_data 21:21:05 INFO - Running post-action listener: _resource_record_post_action 21:21:05 INFO - [mozharness: 2017-09-17 04:21:05.909619Z] Finished run-tests step (success) 21:21:05 INFO - Running post-run listener: _resource_record_post_run 21:21:05 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 10219520; Write bytes: 339828736; Read time: 10404; Write time: 356608 21:21:05 INFO - TinderboxPrint: CPU usage
13.3% 21:21:05 INFO - TinderboxPrint: I/O read bytes / time
10,219,520 / 10,404 21:21:05 INFO - TinderboxPrint: I/O write bytes / time
339,828,736 / 356,608 21:21:05 INFO - TinderboxPrint: CPU idle
8,271.4 (86.7%) 21:21:05 INFO - TinderboxPrint: CPU user
1,201.4 (12.6%) 21:21:05 INFO - TinderboxPrint: Swap in / out
0 / 0 21:21:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13242368; Read time: 0; Write time: 4512 21:21:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:21:06 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 10215424; Write bytes: 326586368; Read time: 10384; Write time: 352096 21:21:06 INFO - Running post-run listener: _upload_blobber_files 21:21:06 INFO - Blob upload gear active. 21:21:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:21:06 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 21:21:06 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'] 21:21:06 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 21:21:06 INFO - (blobuploader) - INFO - Open directory for files ... 21:21:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:21:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:21:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:08 INFO - (blobuploader) - INFO - Done attempting. 21:21:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:21:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:21:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:09 INFO - (blobuploader) - INFO - Done attempting. 21:21:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:21:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:21:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:17 INFO - (blobuploader) - INFO - Done attempting. 21:21:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:21:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:21:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:21:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:21:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:21:18 INFO - (blobuploader) - INFO - Done attempting. 21:21:18 INFO - (blobuploader) - INFO - Iteration through files over. 21:21:18 INFO - Return code: 0 21:21:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:21:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:21:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f632643e21c245ab1bf0965afdd2a5924ca6e10719ff841be805294ef28a9bb16a75b7cb089a112858732e1752a7ea3c2f40ab63804466babeca5f5bd5a1f54f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dfc8ef23b0138804fe9ef5a9084e463add7841a878ca6298cc7ef11852e87be7bbcd612ac197fd8720887aadedbad6e94689df1f3ec8a88ad5ff5efe0d014fa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46d9353fe7e3c5275d32dfd7cb9d5e15dc888668ec16f75a468e97c5289aea3226c6d0ff1d225e1dd977e23feee75f8028698ada5a77a73b2cf1ef900073a0d5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5f21d7054241a7ee578c9b96421385f4f0d0117c3b7c8273e4f4224c0103eec3eeb766226c8f7c84dd690d0135df0b5e188160c3ca351e0a141104a5dd44011"} 21:21:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:21:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:21:18 INFO - Contents: 21:21:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f632643e21c245ab1bf0965afdd2a5924ca6e10719ff841be805294ef28a9bb16a75b7cb089a112858732e1752a7ea3c2f40ab63804466babeca5f5bd5a1f54f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dfc8ef23b0138804fe9ef5a9084e463add7841a878ca6298cc7ef11852e87be7bbcd612ac197fd8720887aadedbad6e94689df1f3ec8a88ad5ff5efe0d014fa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46d9353fe7e3c5275d32dfd7cb9d5e15dc888668ec16f75a468e97c5289aea3226c6d0ff1d225e1dd977e23feee75f8028698ada5a77a73b2cf1ef900073a0d5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5f21d7054241a7ee578c9b96421385f4f0d0117c3b7c8273e4f4224c0103eec3eeb766226c8f7c84dd690d0135df0b5e188160c3ca351e0a141104a5dd44011"} 21:21:18 INFO - Running post-run listener: copy_logs_to_upload_dir 21:21:18 INFO - Copying logs to upload dir... 21:21:18 INFO - mkdir: /builds/slave/test/build/upload/logs 21:21:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.834642 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-16 21:21:18.849798) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 21:21:18.853754) ========= 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=5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f632643e21c245ab1bf0965afdd2a5924ca6e10719ff841be805294ef28a9bb16a75b7cb089a112858732e1752a7ea3c2f40ab63804466babeca5f5bd5a1f54f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dfc8ef23b0138804fe9ef5a9084e463add7841a878ca6298cc7ef11852e87be7bbcd612ac197fd8720887aadedbad6e94689df1f3ec8a88ad5ff5efe0d014fa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46d9353fe7e3c5275d32dfd7cb9d5e15dc888668ec16f75a468e97c5289aea3226c6d0ff1d225e1dd977e23feee75f8028698ada5a77a73b2cf1ef900073a0d5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5f21d7054241a7ee578c9b96421385f4f0d0117c3b7c8273e4f4224c0103eec3eeb766226c8f7c84dd690d0135df0b5e188160c3ca351e0a141104a5dd44011"} build_url:https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/CfqVyKmfSpuuqANp3SS4QQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f632643e21c245ab1bf0965afdd2a5924ca6e10719ff841be805294ef28a9bb16a75b7cb089a112858732e1752a7ea3c2f40ab63804466babeca5f5bd5a1f54f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6dfc8ef23b0138804fe9ef5a9084e463add7841a878ca6298cc7ef11852e87be7bbcd612ac197fd8720887aadedbad6e94689df1f3ec8a88ad5ff5efe0d014fa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46d9353fe7e3c5275d32dfd7cb9d5e15dc888668ec16f75a468e97c5289aea3226c6d0ff1d225e1dd977e23feee75f8028698ada5a77a73b2cf1ef900073a0d5", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5f21d7054241a7ee578c9b96421385f4f0d0117c3b7c8273e4f4224c0103eec3eeb766226c8f7c84dd690d0135df0b5e188160c3ca351e0a141104a5dd44011"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 21:21:18.885625) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:21:18.885990) ========= 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=5cf47e208febbcd81da23b2e0000026f-1505620682.903094-2129287119 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004597 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:21:18.964501) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 21:21:18.964832) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 21:21:18.965137) ========= ========= Total master_lag: 0.36 =========