builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-066 starttime: 1506120953.4 results: success (0) revision: 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:53.395955) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:53.396648) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:53.396943) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:53.484485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:53.484818) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-22 15:55:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 216M=0s 2017-09-22 15:55:53 (216 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135476 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:53.647083) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:53.650419) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020612 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:53.707417) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-22 15:55:53.707818) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 --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 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 --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=82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-22 15:55:53,700 truncating revision to first 12 chars 2017-09-22 15:55:53,700 Setting DEBUG logging. 2017-09-22 15:55:53,700 attempt 1/10 2017-09-22 15:55:53,700 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3d72fdb0e561?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-22 15:55:54,960 unpacking tar archive at: mozilla-central-3d72fdb0e561/testing/mozharness/ program finished with exit code 0 elapsedTime=1.553888 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-22 15:55:55.279601) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:55.279925) ========= script_repo_revision: 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:55.280302) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:55.280594) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 15:55:55.296161) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-22 15:55:55.296788) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:55:55 INFO - MultiFileLogger online at 20170922 15:55:55 in /builds/slave/test 15:55:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:55:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:55:55 INFO - {'append_to_log': False, 15:55:55 INFO - 'base_work_dir': '/builds/slave/test', 15:55:55 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:55:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:55:55 INFO - 'branch': 'Firefox-Non-PGO', 15:55:55 INFO - 'buildbot_json_path': 'buildprops.json', 15:55:55 INFO - 'code_coverage': False, 15:55:55 INFO - 'config_files': ('talos/linux_config.py',), 15:55:55 INFO - 'default_actions': ('clobber', 15:55:55 INFO - 'read-buildbot-config', 15:55:55 INFO - 'download-and-extract', 15:55:55 INFO - 'populate-webroot', 15:55:55 INFO - 'create-virtualenv', 15:55:55 INFO - 'install', 15:55:55 INFO - 'setup-mitmproxy', 15:55:55 INFO - 'run-tests'), 15:55:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:55:55 INFO - 'disable_ccov_upload': False, 15:55:55 INFO - 'disable_stylo': False, 15:55:55 INFO - 'download_minidump_stackwalk': True, 15:55:55 INFO - 'download_symbols': 'ondemand', 15:55:55 INFO - 'enable_stylo': False, 15:55:55 INFO - 'enable_webrender': False, 15:55:55 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:55:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:55:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:55:55 INFO - 'gecko_profile': False, 15:55:55 INFO - 'gecko_profile_interval': 0, 15:55:55 INFO - 'installer_path': 'installer.exe', 15:55:55 INFO - 'log_level': 'info', 15:55:55 INFO - 'log_name': 'talos', 15:55:55 INFO - 'log_to_console': True, 15:55:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:55:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:55:55 INFO - 'opt_config_files': (), 15:55:55 INFO - 'pip_index': False, 15:55:55 INFO - 'suite': 'dromaeojs-e10s', 15:55:55 INFO - 'system_bits': '32', 15:55:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:55:55 INFO - 'title': 'talos-linux64-ix-066', 15:55:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:55:55 INFO - 'use_talos_json': True, 15:55:55 INFO - 'verify': 'False', 15:55:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:55:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:55:55 INFO - 'work_dir': 'build'} 15:55:55 INFO - [mozharness: 2017-09-22 22:55:55.433126Z] Running clobber step. 15:55:55 INFO - Running pre-action listener: _resource_record_pre_action 15:55:55 INFO - Running main action method: clobber 15:55:55 INFO - rmtree: /builds/slave/test/build 15:55:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:55:56 INFO - Running post-action listener: _resource_record_post_action 15:55:56 INFO - [mozharness: 2017-09-22 22:55:56.422396Z] Finished clobber step (success) 15:55:56 INFO - [mozharness: 2017-09-22 22:55:56.422494Z] Running read-buildbot-config step. 15:55:56 INFO - Running pre-action listener: _resource_record_pre_action 15:55:56 INFO - Running main action method: read_buildbot_config 15:55:56 INFO - Using buildbot properties: 15:55:56 INFO - { 15:55:56 INFO - "project": "", 15:55:56 INFO - "product": "firefox", 15:55:56 INFO - "who": "kwierso@gmail.com", 15:55:56 INFO - "installer_path": "public/build/target.tar.bz2", 15:55:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:55:56 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:55:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:55:56 INFO - "stage_platform": "linux64", 15:55:56 INFO - "basedir": "/builds/slave/test", 15:55:56 INFO - "buildnumber": 1171, 15:55:56 INFO - "platform": "ubuntu64_hw", 15:55:56 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:55:56 INFO - "slavebuilddir": "test", 15:55:56 INFO - "taskId": "O1KXNBo1RtmYer-icJg3ow", 15:55:56 INFO - "branch": "mozilla-central", 15:55:56 INFO - "script_repo_revision": "production", 15:55:56 INFO - "revision": "3d72fdb0e561ea59d9e5850c3e71367dbb8a7148", 15:55:56 INFO - "slavename": "talos-linux64-ix-066", 15:55:56 INFO - "repo_path": "mozilla-central" 15:55:56 INFO - } 15:55:56 INFO - Finding installer, test and symbols from parent task. 15:55:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O1KXNBo1RtmYer-icJg3ow'}, attempt #1 15:55:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O1KXNBo1RtmYer-icJg3ow'}, attempt #1 15:55:57 INFO - Task dependencies: Rya6KjrkSJOt9EaGINvG8Q 15:55:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q'}, attempt #1 15:55:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.tar.bz2 15:55:57 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.test_packages.json 15:55:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.crashreporter-symbols.zip 15:55:57 INFO - Running post-action listener: _resource_record_post_action 15:55:57 INFO - [mozharness: 2017-09-22 22:55:57.721001Z] Finished read-buildbot-config step (success) 15:55:57 INFO - [mozharness: 2017-09-22 22:55:57.721219Z] Running download-and-extract step. 15:55:57 INFO - Running pre-action listener: _resource_record_pre_action 15:55:57 INFO - Running main action method: download_and_extract 15:55:57 INFO - mkdir: /builds/slave/test/build/tests 15:55: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')]} 15:55:57 INFO - trying https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.test_packages.json 15:55:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:55:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:55:58 INFO - Downloaded 989 bytes. 15:55:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:55:58 INFO - Using the following test package requirements: 15:55:58 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:55:58 INFO - u'common': [u'target.common.tests.zip'], 15:55:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:55:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:55:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:55:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:55:58 INFO - u'mozbase': [u'target.common.tests.zip'], 15:55:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:55:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:55:58 INFO - u'web-platform': [u'target.common.tests.zip', 15:55:58 INFO - u'target.web-platform.tests.tar.gz'], 15:55:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:55:58 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:55:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.common.tests.zip 15:55:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:55:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.common.tests.zip into memory 15:56:00 INFO - Content-Length response header: 54892793 15:56:00 INFO - Bytes received: 54892793 15:56:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.talos.tests.zip 15:56:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:56:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.talos.tests.zip into memory 15:56:12 INFO - Content-Length response header: 14513823 15:56:12 INFO - Bytes received: 14513823 15:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:13 INFO - trying https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.tar.bz2 15:56:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:56:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:56:16 INFO - Downloaded 61652185 bytes. 15:56:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.tar.bz2 15:56:16 INFO - mkdir: /builds/slave/test/properties 15:56:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:56:16 INFO - Writing to file /builds/slave/test/properties/build_url 15:56:16 INFO - Contents: 15:56:16 INFO - build_url:https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.tar.bz2 15:56:16 INFO - Running post-action listener: _resource_record_post_action 15:56:16 INFO - Running post-action listener: find_tests_for_verification 15:56:16 INFO - Running post-action listener: set_extra_try_arguments 15:56:16 INFO - [mozharness: 2017-09-22 22:56:16.130039Z] Finished download-and-extract step (success) 15:56:16 INFO - [mozharness: 2017-09-22 22:56:16.130270Z] Running populate-webroot step. 15:56:16 INFO - Running pre-action listener: _resource_record_pre_action 15:56:16 INFO - Running main action method: populate_webroot 15:56:16 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:56:16 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['tresize', 15:56:16 INFO - 'tcanvasmark']}, 15:56:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:56:16 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['dromaeo_css', 15:56:16 INFO - 'kraken']}, 15:56:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:56:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:56:16 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:16 INFO - 'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:56:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:56:16 INFO - 'tests': ['damp', 'tps']}, 15:56:16 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:16 INFO - 'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['damp', 'tps']}, 15:56:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:56:16 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['dromaeo_dom']}, 15:56:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:56:16 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['basic_compositor_video', 15:56:16 INFO - 'glvideo']}, 15:56:16 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:56:16 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:56:16 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:16 INFO - 'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['ts_paint_webext', 15:56:16 INFO - 'tp5o_webext']}, 15:56:16 INFO - 'other-e10s': {'tests': ['a11yr', 15:56:16 INFO - 'ts_paint', 15:56:16 INFO - 'tpaint', 15:56:16 INFO - 'sessionrestore', 15:56:16 INFO - 'sessionrestore_many_windows', 15:56:16 INFO - 'sessionrestore_no_auto_restore', 15:56:16 INFO - 'tabpaint']}, 15:56:16 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['a11yr', 15:56:16 INFO - 'ts_paint', 15:56:16 INFO - 'tpaint', 15:56:16 INFO - 'sessionrestore', 15:56:16 INFO - 'sessionrestore_many_windows', 15:56:16 INFO - 'sessionrestore_no_auto_restore', 15:56:16 INFO - 'tabpaint']}, 15:56:16 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 15:56:16 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:56:16 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['perf_reftest_singletons']}, 15:56:16 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['perf_reftest']}, 15:56:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:56:16 INFO - 'tsvgr_opacity', 15:56:16 INFO - 'tart', 15:56:16 INFO - 'tscrollx', 15:56:16 INFO - 'tsvg_static']}, 15:56:16 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['tsvgx', 15:56:16 INFO - 'tsvgr_opacity', 15:56:16 INFO - 'tart', 15:56:16 INFO - 'tscrollx', 15:56:16 INFO - 'tsvg_static']}, 15:56:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:56:16 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:16 INFO - 'talos_options': ['--disable-stylo'], 15:56:16 INFO - 'tests': ['tp5o']}, 15:56:16 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:56:16 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:56:16 INFO - 'talos_options': ['--mitmproxy', 15:56:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:56:16 INFO - '--firstNonBlankPaint'], 15:56:16 INFO - 'tests': ['tp6_google', 15:56:16 INFO - 'tp6_youtube', 15:56:16 INFO - 'tp6_amazon', 15:56:16 INFO - 'tp6_facebook']}, 15:56:16 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:56:16 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:56:16 INFO - 'talos_options': ['--disable-stylo', 15:56:16 INFO - '--mitmproxy', 15:56:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:56:16 INFO - '--firstNonBlankPaint'], 15:56:16 INFO - 'tests': ['tp6_google', 15:56:16 INFO - 'tp6_youtube', 15:56:16 INFO - 'tp6_amazon', 15:56:16 INFO - 'tp6_facebook']}, 15:56:16 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:56:16 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:56:16 INFO - 'talos_options': ['--stylo-threads=1', 15:56:16 INFO - '--mitmproxy', 15:56:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:56:16 INFO - '--firstNonBlankPaint'], 15:56:16 INFO - 'tests': ['tp6_google', 15:56:16 INFO - 'tp6_youtube', 15:56:16 INFO - 'tp6_amazon', 15:56:16 INFO - 'tp6_facebook']}, 15:56:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:56:16 INFO - 'talos_options': ['--xperf_path', 15:56:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:56:16 INFO - 'tests': ['tp5n']}, 15:56:16 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:16 INFO - 'talos_options': ['--disable-stylo', 15:56:16 INFO - '--xperf_path', 15:56:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:56:16 INFO - 'tests': ['tp5n']}}} 15:56:16 INFO - Running post-action listener: _resource_record_post_action 15:56:16 INFO - [mozharness: 2017-09-22 22:56:16.151523Z] Finished populate-webroot step (success) 15:56:16 INFO - [mozharness: 2017-09-22 22:56:16.151612Z] Running create-virtualenv step. 15:56:16 INFO - Running pre-action listener: _resource_record_pre_action 15:56:16 INFO - Running main action method: create_virtualenv 15:56:16 INFO - Creating virtualenv /builds/slave/test/build/venv 15:56:16 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:56:16 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:56:16 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:56:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:56:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:56:16 INFO - Installing distribute.............................................................................................................................................................................................done. 15:56:17 INFO - Installing pip...............done. 15:56:17 INFO - Return code: 0 15:56:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:56:17 INFO - Reading from file tmpfile_stdout 15:56:17 INFO - Output received: 15:56:17 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:56:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:56:17 INFO - Using env: {'DISPLAY': ':0', 15:56:17 INFO - 'HOME': '/home/cltbld', 15:56:17 INFO - 'LANG': 'en_US.UTF-8', 15:56:17 INFO - 'LANGUAGE': 'en_US:en', 15:56:17 INFO - 'LOGNAME': 'cltbld', 15:56:17 INFO - 'MAIL': '/var/mail/cltbld', 15:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:17 INFO - 'MOZ_NO_REMOTE': '1', 15:56:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:17 INFO - 'NO_EM_RESTART': '1', 15:56:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:17 INFO - 'PWD': '/builds/slave/test', 15:56:17 INFO - 'SHELL': '/bin/bash', 15:56:17 INFO - 'SHLVL': '1', 15:56:17 INFO - 'TERM': 'linux', 15:56:17 INFO - 'TMOUT': '86400', 15:56:17 INFO - 'USER': 'cltbld', 15:56:17 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:17 INFO - '_': '/tools/buildbot/bin/python'} 15:56:18 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:56:18 INFO - Downloading/unpacking pip>=1.5 15:56:18 INFO - Running setup.py egg_info for package pip 15:56:18 INFO - warning: no files found matching 'pip/cacert.pem' 15:56:18 INFO - warning: no files found matching '*.html' under directory 'docs' 15:56:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:56:18 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:56:18 INFO - Installing collected packages: pip 15:56:18 INFO - Found existing installation: pip 1.1 15:56:18 INFO - Uninstalling pip: 15:56:18 INFO - Successfully uninstalled pip 15:56:18 INFO - Running setup.py install for pip 15:56:18 INFO - warning: no files found matching 'pip/cacert.pem' 15:56:18 INFO - warning: no files found matching '*.html' under directory 'docs' 15:56:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:56:18 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:56:18 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:56:18 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:56:18 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:56:18 INFO - Successfully installed pip 15:56:18 INFO - Cleaning up... 15:56:18 INFO - Return code: 0 15:56:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:56:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:56:18 INFO - Using env: {'DISPLAY': ':0', 15:56:18 INFO - 'HOME': '/home/cltbld', 15:56:18 INFO - 'LANG': 'en_US.UTF-8', 15:56:18 INFO - 'LANGUAGE': 'en_US:en', 15:56:18 INFO - 'LOGNAME': 'cltbld', 15:56:18 INFO - 'MAIL': '/var/mail/cltbld', 15:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:18 INFO - 'MOZ_NO_REMOTE': '1', 15:56:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:18 INFO - 'NO_EM_RESTART': '1', 15:56:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:18 INFO - 'PWD': '/builds/slave/test', 15:56:18 INFO - 'SHELL': '/bin/bash', 15:56:18 INFO - 'SHLVL': '1', 15:56:18 INFO - 'TERM': 'linux', 15:56:18 INFO - 'TMOUT': '86400', 15:56:18 INFO - 'USER': 'cltbld', 15:56:18 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:18 INFO - '_': '/tools/buildbot/bin/python'} 15:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:18 INFO - Downloading/unpacking psutil>=3.1.1 15:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:56:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:56:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:56:19 INFO - Installing collected packages: psutil 15:56:19 INFO - Running setup.py install for psutil 15:56:19 INFO - building 'psutil._psutil_linux' extension 15:56:19 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 15:56:20 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 15:56:20 INFO - building 'psutil._psutil_posix' extension 15:56:20 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 15:56:20 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 15:56:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:56:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:56:20 INFO - Successfully installed psutil 15:56:20 INFO - Cleaning up... 15:56:20 INFO - Return code: 0 15:56:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:56:20 INFO - Using env: {'DISPLAY': ':0', 15:56:20 INFO - 'HOME': '/home/cltbld', 15:56:20 INFO - 'LANG': 'en_US.UTF-8', 15:56:20 INFO - 'LANGUAGE': 'en_US:en', 15:56:20 INFO - 'LOGNAME': 'cltbld', 15:56:20 INFO - 'MAIL': '/var/mail/cltbld', 15:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:20 INFO - 'MOZ_NO_REMOTE': '1', 15:56:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:20 INFO - 'NO_EM_RESTART': '1', 15:56:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:20 INFO - 'PWD': '/builds/slave/test', 15:56:20 INFO - 'SHELL': '/bin/bash', 15:56:20 INFO - 'SHLVL': '1', 15:56:20 INFO - 'TERM': 'linux', 15:56:20 INFO - 'TMOUT': '86400', 15:56:20 INFO - 'USER': 'cltbld', 15:56:20 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:20 INFO - '_': '/tools/buildbot/bin/python'} 15:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:56:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:56:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:56:22 INFO - Installing collected packages: mozsystemmonitor 15:56:22 INFO - Running setup.py install for mozsystemmonitor 15:56:22 INFO - Successfully installed mozsystemmonitor 15:56:22 INFO - Cleaning up... 15:56:22 INFO - Return code: 0 15:56:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:56:22 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')]} 15:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:22 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')]} 15:56:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:22 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:22 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 15:56:22 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 15:56:22 INFO - Using env: {'DISPLAY': ':0', 15:56:22 INFO - 'HOME': '/home/cltbld', 15:56:22 INFO - 'LANG': 'en_US.UTF-8', 15:56:22 INFO - 'LANGUAGE': 'en_US:en', 15:56:22 INFO - 'LOGNAME': 'cltbld', 15:56:22 INFO - 'MAIL': '/var/mail/cltbld', 15:56:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:22 INFO - 'MOZ_NO_REMOTE': '1', 15:56:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:22 INFO - 'NO_EM_RESTART': '1', 15:56:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:22 INFO - 'PWD': '/builds/slave/test', 15:56:22 INFO - 'SHELL': '/bin/bash', 15:56:22 INFO - 'SHLVL': '1', 15:56:22 INFO - 'TERM': 'linux', 15:56:22 INFO - 'TMOUT': '86400', 15:56:22 INFO - 'USER': 'cltbld', 15:56:22 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:22 INFO - '_': '/tools/buildbot/bin/python'} 15:56:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:22 INFO - Downloading/unpacking jsonschema==2.5.1 15:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:56:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:23 INFO - Downloading functools32-3.2.3-2.tar.gz 15:56:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:56:23 INFO - warning: no files found matching '*.txt' 15:56:23 INFO - no previously-included directories found matching 'build' 15:56:23 INFO - no previously-included directories found matching 'dist' 15:56:23 INFO - no previously-included directories found matching '.git*' 15:56:23 INFO - Installing collected packages: jsonschema, functools32 15:56:23 INFO - Running setup.py install for functools32 15:56:24 INFO - warning: no files found matching '*.txt' 15:56:24 INFO - no previously-included directories found matching 'build' 15:56:24 INFO - no previously-included directories found matching 'dist' 15:56:24 INFO - no previously-included directories found matching '.git*' 15:56:24 INFO - Successfully installed jsonschema functools32 15:56:24 INFO - Cleaning up... 15:56:24 INFO - Return code: 0 15:56:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:24 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:24 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 15:56:24 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 15:56:24 INFO - Using env: {'DISPLAY': ':0', 15:56:24 INFO - 'HOME': '/home/cltbld', 15:56:24 INFO - 'LANG': 'en_US.UTF-8', 15:56:24 INFO - 'LANGUAGE': 'en_US:en', 15:56:24 INFO - 'LOGNAME': 'cltbld', 15:56:24 INFO - 'MAIL': '/var/mail/cltbld', 15:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:24 INFO - 'MOZ_NO_REMOTE': '1', 15:56:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:24 INFO - 'NO_EM_RESTART': '1', 15:56:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:24 INFO - 'PWD': '/builds/slave/test', 15:56:24 INFO - 'SHELL': '/bin/bash', 15:56:24 INFO - 'SHLVL': '1', 15:56:24 INFO - 'TERM': 'linux', 15:56:24 INFO - 'TMOUT': '86400', 15:56:24 INFO - 'USER': 'cltbld', 15:56:24 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:24 INFO - '_': '/tools/buildbot/bin/python'} 15:56:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:56:24 INFO - Cleaning up... 15:56:24 INFO - Return code: 0 15:56:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:24 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:24 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 15:56:24 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 15:56:24 INFO - Using env: {'DISPLAY': ':0', 15:56:24 INFO - 'HOME': '/home/cltbld', 15:56:24 INFO - 'LANG': 'en_US.UTF-8', 15:56:24 INFO - 'LANGUAGE': 'en_US:en', 15:56:24 INFO - 'LOGNAME': 'cltbld', 15:56:24 INFO - 'MAIL': '/var/mail/cltbld', 15:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:24 INFO - 'MOZ_NO_REMOTE': '1', 15:56:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:24 INFO - 'NO_EM_RESTART': '1', 15:56:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:24 INFO - 'PWD': '/builds/slave/test', 15:56:24 INFO - 'SHELL': '/bin/bash', 15:56:24 INFO - 'SHLVL': '1', 15:56:24 INFO - 'TERM': 'linux', 15:56:24 INFO - 'TMOUT': '86400', 15:56:24 INFO - 'USER': 'cltbld', 15:56:24 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:24 INFO - '_': '/tools/buildbot/bin/python'} 15:56:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:24 INFO - Downloading/unpacking blobuploader==1.2.4 15:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:25 INFO - Downloading blobuploader-1.2.4.tar.gz 15:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:56:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:56:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:56:26 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 15:56:26 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 15:56:26 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 15:56:26 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 15:56:26 INFO - Downloading docopt-0.6.1.tar.gz 15:56:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:56:26 INFO - Installing collected packages: blobuploader, requests, docopt 15:56:26 INFO - Running setup.py install for blobuploader 15:56:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:56:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:56:26 INFO - Running setup.py install for requests 15:56:26 INFO - Running setup.py install for docopt 15:56:26 INFO - Successfully installed blobuploader requests docopt 15:56:26 INFO - Cleaning up... 15:56:26 INFO - Return code: 0 15:56:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56: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')]} 15:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56: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')]} 15:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:26 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:26 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 15:56:26 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 15:56:26 INFO - Using env: {'DISPLAY': ':0', 15:56:26 INFO - 'HOME': '/home/cltbld', 15:56:26 INFO - 'LANG': 'en_US.UTF-8', 15:56:26 INFO - 'LANGUAGE': 'en_US:en', 15:56:26 INFO - 'LOGNAME': 'cltbld', 15:56:26 INFO - 'MAIL': '/var/mail/cltbld', 15:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:26 INFO - 'MOZ_NO_REMOTE': '1', 15:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:26 INFO - 'NO_EM_RESTART': '1', 15:56:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:26 INFO - 'PWD': '/builds/slave/test', 15:56:26 INFO - 'SHELL': '/bin/bash', 15:56:26 INFO - 'SHLVL': '1', 15:56:26 INFO - 'TERM': 'linux', 15:56:26 INFO - 'TMOUT': '86400', 15:56:26 INFO - 'USER': 'cltbld', 15:56:26 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:26 INFO - '_': '/tools/buildbot/bin/python'} 15:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:56:27 INFO - Running setup.py (path:/tmp/pip-13XeCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:56:27 INFO - Running setup.py (path:/tmp/pip-kRnjac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:56:27 INFO - Running setup.py (path:/tmp/pip-jKOUs6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:56:27 INFO - Running setup.py (path:/tmp/pip-Ls8HvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:56:27 INFO - Running setup.py (path:/tmp/pip-L4v4O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:56:27 INFO - Running setup.py (path:/tmp/pip-ghCQLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:56:27 INFO - Running setup.py (path:/tmp/pip-xcuGYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:56:27 INFO - Running setup.py (path:/tmp/pip-Ff8GP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:56:27 INFO - Running setup.py (path:/tmp/pip-RA1Jal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:56:27 INFO - Running setup.py (path:/tmp/pip-2_EsAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:56:27 INFO - Running setup.py (path:/tmp/pip-5QXA6H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:56:27 INFO - Running setup.py (path:/tmp/pip-LDr0vF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:56:27 INFO - Running setup.py (path:/tmp/pip-nNgX2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:56:27 INFO - Running setup.py (path:/tmp/pip-NiNbtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:28 INFO - Running setup.py (path:/tmp/pip-uyVnz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:56:28 INFO - Running setup.py (path:/tmp/pip-KBKGwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:56:28 INFO - Running setup.py (path:/tmp/pip-7KZ6ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:56:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:56:28 INFO - Running setup.py install for manifestparser 15:56:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:56:28 INFO - Running setup.py install for mozcrash 15:56:28 INFO - Running setup.py install for mozdebug 15:56:28 INFO - Running setup.py install for mozdevice 15:56:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:56:28 INFO - Running setup.py install for mozfile 15:56:28 INFO - Running setup.py install for mozhttpd 15:56:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:56:28 INFO - Running setup.py install for mozinfo 15:56:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:56:28 INFO - Running setup.py install for mozInstall 15:56:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Running setup.py install for mozleak 15:56:29 INFO - Running setup.py install for mozlog 15:56:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Running setup.py install for moznetwork 15:56:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Running setup.py install for mozprocess 15:56:29 INFO - Running setup.py install for mozprofile 15:56:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Running setup.py install for mozrunner 15:56:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Running setup.py install for mozscreenshot 15:56:29 INFO - Running setup.py install for moztest 15:56:29 INFO - Running setup.py install for mozversion 15:56:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:56:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:56:29 INFO - Cleaning up... 15:56:29 INFO - Return code: 0 15:56:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:30 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:30 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 15:56:30 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 15:56:30 INFO - Using env: {'DISPLAY': ':0', 15:56:30 INFO - 'HOME': '/home/cltbld', 15:56:30 INFO - 'LANG': 'en_US.UTF-8', 15:56:30 INFO - 'LANGUAGE': 'en_US:en', 15:56:30 INFO - 'LOGNAME': 'cltbld', 15:56:30 INFO - 'MAIL': '/var/mail/cltbld', 15:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:30 INFO - 'MOZ_NO_REMOTE': '1', 15:56:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:30 INFO - 'NO_EM_RESTART': '1', 15:56:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:30 INFO - 'PWD': '/builds/slave/test', 15:56:30 INFO - 'SHELL': '/bin/bash', 15:56:30 INFO - 'SHLVL': '1', 15:56:30 INFO - 'TERM': 'linux', 15:56:30 INFO - 'TMOUT': '86400', 15:56:30 INFO - 'USER': 'cltbld', 15:56:30 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:30 INFO - '_': '/tools/buildbot/bin/python'} 15:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:56:30 INFO - Running setup.py (path:/tmp/pip-0ERAXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:56:30 INFO - Running setup.py (path:/tmp/pip-v5Hg1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:56:30 INFO - Running setup.py (path:/tmp/pip-KynAik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:56:30 INFO - Running setup.py (path:/tmp/pip-FlMqi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:56:30 INFO - Running setup.py (path:/tmp/pip-7ZwMc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:56:30 INFO - Running setup.py (path:/tmp/pip-Qc7OCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:56:30 INFO - Running setup.py (path:/tmp/pip-85qc6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:56:30 INFO - Running setup.py (path:/tmp/pip-LUyC3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:56:30 INFO - Running setup.py (path:/tmp/pip-dXzOyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:56:30 INFO - Running setup.py (path:/tmp/pip-8kqdZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:56:30 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)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:56:30 INFO - Running setup.py (path:/tmp/pip-WK4pOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:56:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:56:30 INFO - Running setup.py (path:/tmp/pip-78OW8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:56:31 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)) 15:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:56:31 INFO - Running setup.py (path:/tmp/pip-vKf501-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:56:31 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)) 15:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:56:31 INFO - Running setup.py (path:/tmp/pip-qxyqPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:56:31 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)) 15:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:31 INFO - Running setup.py (path:/tmp/pip-ZOSYjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:31 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)) 15:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:56:31 INFO - Running setup.py (path:/tmp/pip-8HCBJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:56:31 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)) 15:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:56:31 INFO - Running setup.py (path:/tmp/pip-6PPyxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:56:31 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)) 15:56:31 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)) 15:56:31 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)) 15:56:31 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)) 15:56:31 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)) 15:56:31 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)) 15:56:31 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)) 15:56:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:56:31 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 15:56:31 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 15:56:31 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 15:56:31 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 15:56:32 INFO - Downloading blessings-1.6.tar.gz 15:56:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:56:32 INFO - Installing collected packages: blessings 15:56:32 INFO - Running setup.py install for blessings 15:56:32 INFO - Successfully installed blessings 15:56:32 INFO - Cleaning up... 15:56:32 INFO - Return code: 0 15:56:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:56:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:56:32 INFO - Reading from file tmpfile_stdout 15:56:32 INFO - Current package versions: 15:56:32 INFO - argparse == 1.2.1 15:56:32 INFO - blessings == 1.6 15:56:32 INFO - blobuploader == 1.2.4 15:56:32 INFO - docopt == 0.6.1 15:56:32 INFO - functools32 == 3.2.3-2 15:56:32 INFO - jsonschema == 2.5.1 15:56:32 INFO - manifestparser == 1.1 15:56:32 INFO - mozInstall == 1.13 15:56:32 INFO - mozcrash == 1.0 15:56:32 INFO - mozdebug == 0.1 15:56:32 INFO - mozdevice == 0.51 15:56:32 INFO - mozfile == 1.2 15:56:32 INFO - mozhttpd == 0.7 15:56:32 INFO - mozinfo == 0.10 15:56:32 INFO - mozleak == 0.1 15:56:32 INFO - mozlog == 3.5 15:56:32 INFO - moznetwork == 0.27 15:56:32 INFO - mozprocess == 0.25 15:56:32 INFO - mozprofile == 0.28 15:56:32 INFO - mozrunner == 6.13 15:56:32 INFO - mozscreenshot == 0.1 15:56:32 INFO - mozsystemmonitor == 0.3 15:56:32 INFO - moztest == 0.8 15:56:32 INFO - mozversion == 1.4 15:56:32 INFO - psutil == 3.1.1 15:56:32 INFO - requests == 1.2.3 15:56:32 INFO - wsgiref == 0.1.2 15:56:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56: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')]} 15:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56: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')]} 15:56:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:56:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:56:32 INFO - Using env: {'DISPLAY': ':0', 15:56:32 INFO - 'HOME': '/home/cltbld', 15:56:32 INFO - 'LANG': 'en_US.UTF-8', 15:56:32 INFO - 'LANGUAGE': 'en_US:en', 15:56:32 INFO - 'LOGNAME': 'cltbld', 15:56:32 INFO - 'MAIL': '/var/mail/cltbld', 15:56:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:32 INFO - 'MOZ_NO_REMOTE': '1', 15:56:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:32 INFO - 'NO_EM_RESTART': '1', 15:56:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:32 INFO - 'PWD': '/builds/slave/test', 15:56:32 INFO - 'SHELL': '/bin/bash', 15:56:32 INFO - 'SHLVL': '1', 15:56:32 INFO - 'TERM': 'linux', 15:56:32 INFO - 'TMOUT': '86400', 15:56:32 INFO - 'USER': 'cltbld', 15:56:32 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:32 INFO - '_': '/tools/buildbot/bin/python'} 15:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:33 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)) 15:56:33 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)) 15:56:33 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)) 15:56:33 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)) 15:56:33 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)) 15:56:33 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)) 15:56:33 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)) 15:56:33 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)) 15:56:33 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)) 15:56:33 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:56:34 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)) 15:56:34 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)) 15:56:34 INFO - Installing collected packages: simplejson 15:56:34 INFO - Running setup.py install for simplejson 15:56:34 INFO - building 'simplejson._speedups' extension 15:56:34 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 15:56:35 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 15:56:35 INFO - Successfully installed simplejson 15:56:35 INFO - Cleaning up... 15:56:35 INFO - Return code: 0 15:56:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:56:35 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')]} 15:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:35 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')]} 15:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:35 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 0x117a1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1342b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14e79c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', '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 15:56:35 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 15:56:35 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 15:56:35 INFO - Using env: {'DISPLAY': ':0', 15:56:35 INFO - 'HOME': '/home/cltbld', 15:56:35 INFO - 'LANG': 'en_US.UTF-8', 15:56:35 INFO - 'LANGUAGE': 'en_US:en', 15:56:35 INFO - 'LOGNAME': 'cltbld', 15:56:35 INFO - 'MAIL': '/var/mail/cltbld', 15:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:35 INFO - 'MOZ_NO_REMOTE': '1', 15:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:35 INFO - 'NO_EM_RESTART': '1', 15:56:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:35 INFO - 'PWD': '/builds/slave/test', 15:56:35 INFO - 'SHELL': '/bin/bash', 15:56:35 INFO - 'SHLVL': '1', 15:56:35 INFO - 'TERM': 'linux', 15:56:35 INFO - 'TMOUT': '86400', 15:56:35 INFO - 'USER': 'cltbld', 15:56:35 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:35 INFO - '_': '/tools/buildbot/bin/python'} 15:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:56:35 INFO - Cleaning up... 15:56:35 INFO - Return code: 0 15:56:35 INFO - Running post-action listener: _resource_record_post_action 15:56:35 INFO - Running post-action listener: _start_resource_monitoring 15:56:35 INFO - Starting resource monitoring. 15:56:35 INFO - [mozharness: 2017-09-22 22:56:35.702616Z] Finished create-virtualenv step (success) 15:56:35 INFO - [mozharness: 2017-09-22 22:56:35.703084Z] Running install step. 15:56:35 INFO - Running pre-action listener: _resource_record_pre_action 15:56:35 INFO - Running main action method: install 15:56:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:56:35 INFO - Reading from file tmpfile_stdout 15:56:35 INFO - Detecting whether we're running mozinstall >=1.0... 15:56:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:56:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:56:36 INFO - Reading from file tmpfile_stdout 15:56:36 INFO - Output received: 15:56:36 INFO - Usage: mozinstall [options] installer 15:56:36 INFO - Options: 15:56:36 INFO - -h, --help show this help message and exit 15:56:36 INFO - -d DEST, --destination=DEST 15:56:36 INFO - Directory to install application into. [default: 15:56:36 INFO - "/builds/slave/test"] 15:56:36 INFO - --app=APP Application being installed. [default: firefox] 15:56:36 INFO - mkdir: /builds/slave/test/build/application 15:56:36 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'] 15:56:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:56:53 INFO - Reading from file tmpfile_stdout 15:56:53 INFO - Output received: 15:56:53 INFO - /builds/slave/test/build/application/firefox/firefox 15:56:53 INFO - Running post-action listener: _resource_record_post_action 15:56:53 INFO - [mozharness: 2017-09-22 22:56:53.833936Z] Finished install step (success) 15:56:53 INFO - [mozharness: 2017-09-22 22:56:53.834084Z] Running setup-mitmproxy step. 15:56:53 INFO - Running pre-action listener: _resource_record_pre_action 15:56:53 INFO - Running main action method: setup_mitmproxy 15:56:53 INFO - Skipping: mitmproxy is not required 15:56:53 INFO - Running post-action listener: _resource_record_post_action 15:56:53 INFO - [mozharness: 2017-09-22 22:56:53.834618Z] Finished setup-mitmproxy step (success) 15:56:53 INFO - [mozharness: 2017-09-22 22:56:53.834722Z] Running run-tests step. 15:56:53 INFO - Running pre-action listener: _resource_record_pre_action 15:56:53 INFO - Running pre-action listener: _set_gcov_prefix 15:56:53 INFO - Running main action method: run_tests 15:56:53 WARNING - Try message not found. 15:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:56:53 INFO - Python 2.7.3 15:56:53 INFO - Return code: 0 15:56:53 INFO - grabbing minidump binary from tooltool 15:56:53 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')]} 15:56:53 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 0x14e4d60>, '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 0x14e5a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14e5ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:56:53 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 15:56:53 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 15:56:53 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 15:56:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:56:53 INFO - Return code: 0 15:56:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:56:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:56:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:56:53 INFO - ENV: RUST_BACKTRACE is now full 15:56:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:56:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 15:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 15:56:53 INFO - Using env: {'DISPLAY': ':0', 15:56:53 INFO - 'HOME': '/home/cltbld', 15:56:53 INFO - 'LANG': 'en_US.UTF-8', 15:56:53 INFO - 'LANGUAGE': 'en_US:en', 15:56:53 INFO - 'LOGNAME': 'cltbld', 15:56:53 INFO - 'MAIL': '/var/mail/cltbld', 15:56:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:53 INFO - 'MOZ_NO_REMOTE': '1', 15:56:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:53 INFO - 'NO_EM_RESTART': '1', 15:56:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:53 INFO - 'PWD': '/builds/slave/test', 15:56:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:53 INFO - 'RUST_BACKTRACE': 'full', 15:56:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:56:53 INFO - 'SHELL': '/bin/bash', 15:56:53 INFO - 'SHLVL': '1', 15:56:53 INFO - 'TERM': 'linux', 15:56:53 INFO - 'TMOUT': '86400', 15:56:53 INFO - 'USER': 'cltbld', 15:56:53 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:53 INFO - '_': '/tools/buildbot/bin/python'} 15:56:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 15:56:54 INFO - mozversion application_buildid: 20170922215617 15:56:54 INFO - mozversion application_changeset: 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 15:56:54 INFO - mozversion application_display_name: Nightly 15:56:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:56:54 INFO - mozversion application_name: Firefox 15:56:54 INFO - mozversion application_remotingname: firefox 15:56:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:56:54 INFO - mozversion application_vendor: Mozilla 15:56:54 INFO - mozversion application_version: 58.0a1 15:56:54 INFO - mozversion platform_buildid: 20170922215617 15:56:54 INFO - mozversion platform_changeset: 3d72fdb0e561ea59d9e5850c3e71367dbb8a7148 15:56:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:56:54 INFO - mozversion platform_version: 58.0a1 15:56:54 INFO - using testdate: 1506121014 15:56:54 INFO - actual date: 1506121014 15:56:54 INFO - starting webserver on 'localhost:55199' 15:56:54 INFO - SUITE-START | Running 2 tests 15:56:54 INFO - TEST-START | dromaeo_css 15:56:54 INFO - Initialising browser for dromaeo_css test... 15:56:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55199/getInfo.html -profile /tmp/tmpTEJtPe/profile 15:56:54 INFO - TEST-INFO | started process 9530 (/builds/slave/test/build/application/firefox/firefox http://localhost:55199/getInfo.html) 15:57:00 INFO - TEST-INFO | 9530: exit 0 15:57:00 INFO - Browser initialized. 15:57:00 INFO - Running cycle 1/1 for dromaeo_css test... 15:57:00 INFO - Using env: {'DISPLAY': ':0', 15:57:00 INFO - 'HOME': '/home/cltbld', 15:57:00 INFO - 'JSGC_DISABLE_POISONING': '1', 15:57:00 INFO - 'LANG': 'en_US.UTF-8', 15:57:00 INFO - 'LANGUAGE': 'en_US:en', 15:57:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:57:00 INFO - 'LOGNAME': 'cltbld', 15:57:00 INFO - 'MAIL': '/var/mail/cltbld', 15:57:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:57:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:57:00 INFO - 'MOZ_CRASHREPORTER': '1', 15:57:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:57:00 INFO - 'MOZ_NO_REMOTE': '1', 15:57:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:57:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:00 INFO - 'NO_EM_RESTART': '1', 15:57:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:00 INFO - 'PWD': '/builds/slave/test', 15:57:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:57:00 INFO - 'RUST_BACKTRACE': 'full', 15:57:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:57:00 INFO - 'SHELL': '/bin/bash', 15:57:00 INFO - 'SHLVL': '1', 15:57:00 INFO - 'TERM': 'linux', 15:57:00 INFO - 'TMOUT': '86400', 15:57:00 INFO - 'USER': 'cltbld', 15:57:00 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 15:57:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:00 INFO - '_': '/tools/buildbot/bin/python'} 15:57:00 INFO - TEST-INFO | started process 9764 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTEJtPe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:57:01 INFO - PID 9764 | 15:57:01 INFO - PID 9764 | (/builds/slave/test/build/application/firefox/firefox:9813): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:57:01 INFO - PID 9764 | 15:57:11 INFO - PID 9764 | RSS: Main: 151207936 15:57:11 INFO - PID 9764 | 16:00:08 INFO - PID 9764 | Cycle 1(1): loaded http://localhost:55199/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55199/tests/dromaeo/cssquery-ext.html) 16:00:09 INFO - PID 9764 | RSS: Main: 152850432 16:00:09 INFO - PID 9764 | 16:03:11 INFO - PID 9764 | Cycle 1(1): loaded http://localhost:55199/tests/dromaeo/cssquery-ext.html (next: http://localhost:55199/tests/dromaeo/cssquery-jquery.html) 16:03:12 INFO - PID 9764 | RSS: Main: 152080384 16:03:12 INFO - PID 9764 | 16:06:14 INFO - PID 9764 | Cycle 1(1): loaded http://localhost:55199/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55199/tests/dromaeo/cssquery-mootools.html) 16:06:14 INFO - PID 9764 | RSS: Main: 154263552 16:06:14 INFO - PID 9764 | 16:09:16 INFO - PID 9764 | Cycle 1(1): loaded http://localhost:55199/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55199/tests/dromaeo/cssquery-prototype.html) 16:09:17 INFO - PID 9764 | RSS: Main: 153022464 16:09:17 INFO - PID 9764 | 16:12:20 INFO - PID 9764 | Cycle 1(1): loaded http://localhost:55199/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55199/tests/dromaeo/cssquery-yui.html) 16:12:20 INFO - PID 9764 | RSS: Main: 152580096 16:12:20 INFO - PID 9764 | 16:15:22 INFO - PID 9764 | Cycle 1(1): loaded http://localhost:55199/tests/dromaeo/cssquery-yui.html (next: http://localhost:55199/tests/dromaeo/cssquery-dojo.html) 16:15:22 INFO - PID 9764 | RSS: Main: 156442624 16:15:22 INFO - PID 9764 | 16:15:22 INFO - PID 9764 | __start_tp_report 16:15:22 INFO - PID 9764 | _x_x_mozilla_page_load 16:15:22 INFO - PID 9764 | _x_x_mozilla_page_load_details 16:15:22 INFO - PID 9764 | |i|pagename|runs| 16:15:22 INFO - PID 9764 | |0;dojo.html;3126.71859532642;3145.370925814836;3151.7005884440982;3155.463571192897;3156.1478400831775;6165.784197553086;6236.407541283571;6256.84357891053;6270.4670103041235;6377.713002914868;6296.307406185316;6314.6842657867155;6358.205224346957;6402.391772781578;6409.038644203371;5596.916046259309;5673.659580425167;5808.82573522795;5809.215755872945;5873.030949893284;5726.541876649879;5778.884422311572;5780.653160810365;5823.184754134425;5832.445917637818;5663.0655941769555;5697.715114244271;5752.252207213078;5756.827295181151;5766.769329226847;5628.943710562883;5728.598998070137;5747.971260143672;5760.567957403212;5793.768249270024;5748.7125643717645;5757.366689664133;5807.535397168216;5821.15593238978;5851.297844258674;6115.143879856823;6245.406686364788;6253.749850005995;6256.0615907613765;6278.811635650892;6694.799156025327;6777.525573209878;6797.6601169941305;6804.285550017276;6826.112605361321;6758.594484330956;6764.222114456851;6839.0425340452375;6857.382835544775;6865.691043903036;6074.51403887688;6208.72060757267;6224.346443623445;6276.466500347476;6357.332480089616;6838.658067096725;6860.5197636165985;6883.27725588816;6896.241413444517;6921.169459664772;6567.8358041048405;6694.230163531159;6707.5304728668525;6716.496262780311;6724.125863637696;6730.427913627396;6733.966330168318;6815.352541508549;6816.352446517573;6823.38589526935;6540.05169250456;6618.437432818168;6703.363180497845;6745.797626071226;6810.216825065082;6637;6671.032700258435;6762.661866906635;6773.491988100912;6780.864382712318;5281.181416880389;5285.894282114413;5322.654027488201;5361.758720857571;5365.892682146336;4618.099470603267;4655.2784318430695;4670.322803193518;4675.485696573374;4686.601638860735;5676.460736230051;5782.710864456761;5783.768649253982;5792.652440853562;5870.383609721003;6574.5397822152945;6604.768833090819;6737.056812046318;6820.556663816836;6826.863462730717;5790.652560846278;5790.942090579125;5808.332041815245;5825.24271844666;5861.501772349398;5750.913736293959;5772.191893134965;5777.24895763548;5828.708564571754;5854.824355269348;6392.904106438407;6452.774152904626;6490.350964903472;6495.155629768195;6604.075429439882;6607.933920660828;6640.402363787282;6640.966795165993;6642.335766423319;6677.198736151693;6443.903341449788;6459.483241340776;6490.480761538971;6556.508261880283;6571.310012448624;6608.372204640552;6687.799366019027;6697.866042679022;6704.765333213314;6710.194776626836;6755.223149337692;6758.155230596176;6786.355296246848;6839.452843772387;6850.520463567503;6369.49044076482;6486.2216534016225;6487.610743355225;6592.604443733391;6598.703058362289;6534.444572211225;6564.113844630913;6564.80305590833;6566.868662626625;6567.540272180901;6342.873142537216;6424.325445828307;6448.548601597843;6464.709088091141;6490.286068532552;6415.166028416276;6480.449161821298;6507.609543427409;6580.374864387875;6583.604983700993;6246.031865060922;6310.274318453253;6337.176167098248;6341.143945567474;6398.456131228898 16:15:22 INFO - PID 9764 | |1;ext.html;882.3558802074485;886.667499687617;887.6582515731442;893.1827377949177;916.8670542771298;39877.2024559509;40302.59697403025;40486.392704109465;40539;40566;37230;37508.81245593768;37548.43677344835;37570.812145939235;37717;7122.394596459294;7159.105111861018;7256.4920455568135;7258.600776957292;7287.2712728726965;7103.68033438494;7164.7134114635355;7211.278872112773;7228.349448549629;7234.565926044428;7667.961660191691;7918.80202994924;7919.089304729943;7962.084360298553;8009.519428834259;7889.644965976545;7895.407844411692;7927.445078844483;7930.405219608524;7945.165757595427;7907.683692652345;7986.6805327786815;7992;7994;7998.920010799876;37887.431688524695;38080.23839523222;38199.427008594896;38289.80855095734;38349.616503834884;3547.219611685425;3584.068142282999;3584.5877724061806;3586.9820650896577;3595.209054008114;30925.845370773226;31095.84452077748;31104.533431998538;31374.686253137406;31449.37101257985;5209.453007434203;5231.764570594294;5254.343207099113;5254.395744489394;5290.1800220965815;189143.4885430159;191908;193189;193879;194316;48564.51435485671;48695.756521217874;48745.51254487481;48848.26727599089;49086;50415.49584504108;50527.24209136866;50598.73503162451;50651;50661.240081398806;14546.781798273034;14827.77758333626;14830.629234269014;14873;14886.330115144845;12032.81950770739;12060.21608595456;12082.516699332104;12083.697907552381;12097.395130243629;13864.445422183;13946.302684865715;14105.576832695035;14219.075760075562;14242.07426517273;5279.366476022902;5371.6239863209985;5413.810516631899;5502;5505.642133261325;995.6913356859396;1001.5142655811925;1021.2238698588977;1024.4007255755319;1026.8305729554688;22466.55066898653;22795;22804.771952280265;22808.315750527512;22964.885175574014;1104.5857803323754;1108.4291589831244;1108.778244351133;1117.5641499815079;1120.590984290728;12068.275903445645;12153.392330383445;12202.267863928193;12240.81638775419;12259.69350766238;228310;229043;229640;229645;229897;44164;44779.328310075376;50722.49277507252;51289;51666.48333516692;1432.5129455985016;1456.5848733111013;1456.8761655259423;1470.3897882378692;1473.7273604383224;27041.459170816866;27233.31916702098;27724.445511090067;27734.861325693244;27785.166445006682;37619.62380376161;37660.811695941346;37663;37664.246715065;37723;206502;208010;208498;208816;208858;32388.35223295568;33464.665353347125;33479.66520334766;33738.49392259069;33914.66085339214;42615;42660.36009459923;43187;43216;43293.567064328956;30897;31246;31412.528812068293;31498.685013149574;31668.5249721259;31383.372332552764;31708.524372133976;31717.841410792797;31739.682603174595;31938;36137.81931090433;36170.09574760652;36393.454098189075;36446.817765911;36483.45274820827;30880.382392352476;31146.688533115284;31281.217969550944;31374.37251255008;31835.20411989719;22266.777332227117;23183;23204;23263.41841453977;23558.646620300366 16:15:22 INFO - PID 9764 | |2;jquery.html;579.7709904587688;585.3151812379516;590.4302348233954;595.565237376715;619.4115590189319;21951.231706890263;22678;22707.659385109237;22723;22726.97728602215;23419.5316093678;24243.27270181893;24285;24392.268231953025;24502.387440313956;6376.330485299047;6571.3428657134145;6572.309907459719;6581.5721978071515;6583.604983700993;6359.8410039748915;6436.517261205405;6480.578762380454;6514.088027676129;6516.250631177404;6609.173853268341;6609.570377925444;6637.966810165943;6644.93355066448;6649.734010639569;6600.504962127836;6611.008348747679;6613.239477460082;6614.900776488356;6638.86722265552;5841.620294680874;6043.153958445821;6062.636241825505;6128.203333566607;6168.938310616882;27131.45737085267;27606.861965690245;27695.723042769518;27798;27816.16551503458;2038.6024725178597;2043.3052762060822;2055.825254853339;2057.1154403606442;2060.124447109972;18804.059797010093;19379.80620193794;19670.114844832016;19688.704669429826;19709.704354434696;2466.3464181991785;2488.054539275082;2524.129175434472;2524.394145405108;2525.936851578707;124221.37889310587;131501.34249328787;132211;138793;144316;14658.207898175657;14750.114993100447;14892.255387230813;14973.700525989638;15230;23042.423939401648;23045.769542304697;23079.76920230776;23130.190443334403;23144.42138946506;9701.6604418845;9755.073268039667;9785.510724463747;9836.458994755214;9869.161121304625;8903;8909.866352004596;9020.233280171127;9020.954895225483;9026.052264512264;9163.725088247364;9268.397554158959;9272.350935434455;9280.257579393632;9364.25085993119;2673.9598906016427;2755.8484283364205;2776.5557510798176;2835.844028578448;2881.3228891210547;949.928755343352;978.4373984958677;982.513655740413;986.9358491698017;988.6440881282732;18371.72442413365;18387.161283871774;18729.438116856516;19012;19445;1036.9377837329785;1038.1746511523206;1051.621416290135;1063.5214153630898;1067.0396643021343;10367.32612380193;10406.323588966692;10496.790064198673;10523.052925236765;10776.137908967265;468091;493344;500609;510298;510622;112428;113701.431492842;114280;114528;117616.41191793987;1139.2765593847967;1192.3859212505567;1235.3576140407117;1257.9559715409916;1259.1311994723794;19812.70280945816;19875.801241987396;20123;20516.076776544793;20528.178872844917;58966.70516647389;59789;60134.39865601463;60263.397366025776;61217.38782612117;71142.28857711564;71689;72056.63971680107;74618.25381746113;77477.61261193884;13872.23702696321;14174.858251417354;14192.929035354757;14218.289085545475;14269.71460570762;21128.471788205417;21253.36239912805;21501.354959351243;21674.24140155087;21691.566168676854;16459.83540164583;16727.163641818104;16806.83193168053;16814.747778783574;16828.495145145665;15092.39630414771;15615;15855.20723963774;16337.101459419371;16379.181040947667;16882.915585421993;17621.911890440464;17769.911150444164;18161.545961350545;18176;13908;13990.510332138327;14052.578422647337;14071.366788494746;14094.083884547472;17280.308787648355;17335.653286934445;17368.82631173672;17458.825411746227;17546.82453175452 16:15:22 INFO - PID 9764 | |3;mootools.html;1351.8512963574005;1401.5935378740166;1417.3267697843526;1420.8366037905644;1428.0860249440357;14980;15706.607334816636;15711.978721383102;15712.52862414128;15720.056796592213;15385;15461.768073478906;15555.144467054308;15569.29938152783;15591.610209744762;2787.414642924983;2793.4971705093076;2835.7022512636186;2838.489071967045;2847.117393607988;2678.1563807400607;2716.945661086787;2727.740864617868;2755.8622068896475;2761.4753196892525;2719.5104881121392;2790.1768978151395;2876.4822331980236;2877.5252083406212;2886.552584349419;2853.386521897802;2858.4426036922705;2874.798764086515;2876.1084063940143;2879.7840161987933;2682.785377169822;2707.2013755942044;2712.4303896181827;2716.456708658276;2727.986360068207;14690.926545367312;14863.108213507225;14924.403023879031;15015.249237538079;15060.171690557016;8181.468204566684;8452.957735211347;8474.533900635462;8521.531315777629;8536.8719469208;5286.259923610698;5333;5455.754491047913;5532.972335138339;5569.66582005081;2010.1657812008145;2011.517235863397;2014.4359579317668;2031.0758604834764;2051.7640471345835;26512.73487265122;26621.73378266231;26644.33389165267;26666;26744.19767406981;6963.9303606963285;6982.476314276348;7078.893816592756;7157.57054576727;7186.317299856505;17493.65012699739;17589.64820703579;17590.824091759176;17597.208125634377;17608.11959402025;8168.877466838002;8336.541490218096;8347.248747612744;8360.498370097814;8388.28699560532;7271.67277472515;7371.741989030358;7453.664585093684;7482.064741907262;7525.58609276495;5226.660267082628;5400.432954539795;5411.648242864201;5435.347758269033;5470.972645136829;1403.2282244765338;1411.2943528235883;1560.9921950390176;1587.2540205773557;1594.4419453191522;891.1133422244778;906.7823722306599;909.5497728624373;918.7335672654855;920.7559996600907;8522.190391912882;8529.360297977677;8690.391672582986;8796.47221166732;8825.426347287406;952.7760976170595;974.8927617962019;976.1848856205148;990.1633120013577;1012.9037741414554;5296.443873393238;5361.597880158925;5503.229547863302;5511.696856672841;5576.888462230814;21976.780232197794;22537.887310563343;22833.885830571075;22849.771502285097;23017.424564386023;20929.790702093087;22167.778322216895;22473.32580022602;22588.66117008246;22720.772792272197;11099.001089901947;11213.327200368005;11223.270487418455;11257.099432045417;11272.830907536394;8194.877076843972;8484.957575212084;8566.443181193203;8701.216890479634;8721.433106848288;18099.276028958695;18192.909035454737;18972.430827075208;19196.424107276805;19265.325713599956;19084.473242140317;19162.808371916104;19182.520436989187;19305.80694193096;19340.323088692392;10659.307145035547;10690.62582809598;10939.890601093886;10941.671749847517;10984.670459886216;13945.093568917988;14016;14092.084014539023;14094.295285235492;14164.362603682654;10132.544035518273;10564.577416903237;11610.94194529022;11784.528618855493;11799.292042477478;11272.549098036316;11364.886351136383;11375.20373573842;11432.256903301259;11709;9867.851982220129;9886.11025007724;10144;10163.390196588229;10193.133583645176;7787.5716835574585;7866.72466463671;7874.645640946285;7881.330086942674;7930.246626570467;7574.280443357872;7678.1937896522295;7681.270279323456;7705.80735481595;7813.804654883674 16:15:22 INFO - PID 9764 | |4;prototype.html;502.67326237945355;531.9148936170212;537.4303238567118;537.8574677710405;542.6608369768894;6134.202553668022;6144.416280453361;6228.726514822091;6345.7461701532;6440.935590644104;6289.937100629004;6297.653629050412;6314.210723659543;6327.544664727104;6354.110424540557;5182.149817600323;5221.164613661806;5265.736713164346;5295.152775555912;5295.761690723907;5073.4926507349155;5259.31628888244;5315.920261196085;5328.653637513549;5334.786608535654;5305.336832895888;5316.362036555619;5317.813876514323;5321.088999585301;5363.570914326844;5245.082110630643;5261.947380526203;5273.314469119028;5310.575153987672;5361.892762144775;4968.552830245259;4988.750562471898;5033.798648054073;5034.09386310464;5059.443461219263;6318.87362252757;6335.271443783978;6336.271328797201;6378.68106594673;6392.616443013388;5904.671448924;6033.336333003366;6046.213992181032;6077.3314935357075;6092.756289748405;6663.966680166616;6709.295523969961;6754.628495432749;6761.661916904184;6778.508728079814;5406.810761623364;5415.918761218585;5422.647527910713;5439.782408703687;5467.589930755209;7008.824779380505;7048.295170482962;7062.611556364398;7062.6821793019435;7062.823429414203;5603.131514615241;5620.437956204347;5629.102680667846;5635.915461268084;5639.915401268984;5661.235733176051;5685.403032681592;5721;5735.426457354231;5744.511716504144;6064.514838812885;6090.086487026892;6100.359462256399;6101.969490152521;6115.1744514490865;6435.710393032325;6743.730250789913;6799.864002719918;6818.761343352959;6831.385175334244;1852.2313240005517;1882.8117188281337;1906.761654793151;1940.2723978508059;1961.4507937777446;1426.3937826423728;1440.3590402270888;1441.1929319581068;1467.6037469883072;1493.5817970968146;5701.458361455655;5760.724513817019;5786.334571524244;5788.942110578924;5844.211031510692;6536.967315163393;6661.966690166518;6673.933260667428;6754.750371181347;6767.1879374475375;5688.9759843227785;5698.344690360579;5765.308163020464;5827.155062515912;5838.007538718428;3551.236484155919;3580.695640870503;3622.185008373095;3633.1643721943574;3655.94516082259;6552.410283074546;6553.770618028441;6576.934230657632;6585.012248162814;6668.666566671647;6946.93053069473;6959.886418173068;6977.278861244119;7016.649167541603;7031.242189452637;6622.807894578926;6630.602163870183;6675.3324667533825;6686.364795344434;6686.83282917931;6810.569780346183;6837.931620683729;6868.484863635147;6873.656317184221;6906.171259448898;6731.629760363227;6749.257581666111;6768.593884366954;6806.591604503746;6865.622390768555;6523.64119973406;6558.147440832852;6575.704093315717;6581.4533584606625;6614.10709554204;5064.620153488429;5085.211792172219;5094.592432605308;5137.691738495702;5144.382674078985;6516.413522782972;6710.597364158166;6729.629870357177;6732.980105968092;6857.622830744157;6236.220472440945;6402.391772781578;6466.030095485715;6484.773032943825;6493.155889734304;6475.158229430144;6542.8364290893105;6560.212774467094;6613.470922326106;6624.503162262753;6455.3221911700475;6461.483081353575;6542.901856472062;6546.214454265519;6567.343265673395;6479.449246814073;6535.771248006297;6602.88707613559;6673.9666301668185;6720.1263835703;6435.356464353527;6438.967805160944;6455.289918108911;6465.515086368635;6471.902921456276 16:15:22 INFO - PID 9764 | |5;yui.html;900.4327273817494;917.8347897378471;937.2455173585261;942.9104235097664;953.9379940303882;19629.60740785185;20346;20347.389578312657;20375.694364584506;20392.286269980552;18796.34212802552;18823.152958116883;19023.904880475613;19564.80435195651;19708.310209142684;961.6297725375729;974.4786539201529;977.9168770654503;978.7063880835757;979.3095867413457;966.5747071288646;980.8234517786796;984.5126662302171;990.3315262198023;1006.783541538569;1048.4757621189406;1049.8582691336687;1050.2700623066971;1051.3796859852685;1051.4059556350676;990.1830989433674;1012.4077414712402;1027.9897201028007;1036.8755749310055;1041.7551875309296;746;746.660269577341;753.1188509443964;753.8906858505541;754.7999780058288;20468.283610073577;20641.8967905161;20792.896035519876;20802.375928722162;20813.375598732062;21140.89429552858;21429.14283428661;21436.89281553598;21536.78463215379;21564.35306940794;17519;17590.736138957927;18212.726809097872;18373.08134593265;18387.72418413725;3825.5218097737784;3851.903702407434;3855.749376290532;3857.1321452673205;3858.536975562922;133891.33054334667;134102;134182;134312;136113.3194334022;16667.749983750255;16946.576335591708;16962;17078.146092695315;17212.827871721373;30236.546451803242;31112.68887311098;31146.065618031495;31449.21376965594;31467.685323146932;8892.377533572717;8893.73318800437;8922.375433719577;8935.106489351143;8974.506402147945;4278.465191851019;4346.478422589246;4436.689431739747;4448.399466072104;4477.082198149372;5365.704886231217;5484.314460692413;5493.340799104133;5498.642588231832;5504.091824848935;957.7366224288377;972.5879818051272;982.4007355513131;987.4075554667286;991.5191132300828;688.7072993977539;713.2546488919093;717.8923161525709;720.5208536323391;720.8270015196315;12120.696982575506;12136.393180341125;12237.75524489523;12585.307808070644;13013.804792928113;632.3581564711822;639.402158981354;646.9838254043686;648.672420427681;649.7206201333378;5736.971315143481;5754.5971781975695;5775.726581015342;5913.49735272498;5955.79154729591;119224.40387798006;120392;121423.39288303678;121682;123574.38212809058;108464.45767771268;108857.45571272093;108926.45536772424;111910.44044779887;112035;92348.53825730829;93123.53438232765;93344.53327733454;95290;96341;10836.064918452039;10846.837297440386;10969.286996345212;11012;11165.49755260999;45052.549474504835;45621.315680265485;45628.31557526573;46164.53835461602;47254.29118563293;59240;59715.40284597098;59938.40061599328;60065.69967150136;62161.37838621556;15048.172350520827;15471.458498952483;15494.5351639451;15549.222538872786;15647.217639118226;16332.428365007643;16563.171841408122;16614.25235864413;17013.574660633087;17014.74477882808;14581;14648.780268295772;14681.265936702908;14791.630209244855;14935.775963360775;15821.604459888134;16040.679186415973;16044.278007489924;16094.275757591171;16167;14727.55817325482;14871.776923346375;14913.776293355391;15014;15036.248187590358;11465.713357166138;11631.6510504685;11647.417629118341;11784.823227651763;11859.16985810985;10581.31221470602;10703.357798532114;10748.247622666338;10889.183311251529;11006 16:15:22 INFO - PID 9764 | __end_tp_report 16:15:22 INFO - PID 9764 | __start_cc_report 16:15:22 INFO - PID 9764 | _x_x_mozilla_cycle_collect,294 16:15:22 INFO - PID 9764 | __end_cc_report 16:15:22 INFO - PID 9764 | __startTimestamp1506122122812__endTimestamp 16:15:22 INFO - PID 9764 | 16:15:22 INFO - PID 9764 | ------- Summary: start ------- 16:15:22 INFO - PID 9764 | Number of tests: 6 16:15:22 INFO - PID 9764 | 16:15:22 INFO - PID 9764 | [#0] dojo.html Cycles:165 Average:6179.27 Median:6409.04 stddev:739.07 (11.5%) stddev-sans-first:701.47 16:15:22 INFO - PID 9764 | Values: 3126.7 3145.4 3151.7 3155.5 3156.1 6165.8 6236.4 6256.8 6270.5 6377.7 6296.3 6314.7 6358.2 6402.4 6409.0 5596.9 5673.7 5808.8 5809.2 5873.0 5726.5 5778.9 5780.7 5823.2 5832.4 5663.1 5697.7 5752.3 5756.8 5766.8 5628.9 5728.6 5748.0 5760.6 5793.8 5748.7 5757.4 5807.5 5821.2 5851.3 6115.1 6245.4 6253.7 6256.1 6278.8 6694.8 6777.5 6797.7 6804.3 6826.1 6758.6 6764.2 6839.0 6857.4 6865.7 6074.5 6208.7 6224.3 6276.5 6357.3 6838.7 6860.5 6883.3 6896.2 6921.2 6567.8 6694.2 6707.5 6716.5 6724.1 6730.4 6734.0 6815.4 6816.4 6823.4 6540.1 6618.4 6703.4 6745.8 6810.2 6637.0 6671.0 6762.7 6773.5 6780.9 5281.2 5285.9 5322.7 5361.8 5365.9 4618.1 4655.3 4670.3 4675.5 4686.6 5676.5 5782.7 5783.8 5792.7 5870.4 6574.5 6604.8 6737.1 6820.6 6826.9 5790.7 5790.9 5808.3 5825.2 5861.5 5750.9 5772.2 5777.2 5828.7 5854.8 6392.9 6452.8 6490.4 6495.2 6604.1 6607.9 6640.4 6641.0 6642.3 6677.2 6443.9 6459.5 6490.5 6556.5 6571.3 6608.4 6687.8 6697.9 6704.8 6710.2 6755.2 6758.2 6786.4 6839.5 6850.5 6369.5 6486.2 6487.6 6592.6 6598.7 6534.4 6564.1 6564.8 6566.9 6567.5 6342.9 6424.3 6448.5 6464.7 6490.3 6415.2 6480.4 6507.6 6580.4 6583.6 6246.0 6310.3 6337.2 6341.1 6398.5 16:15:22 INFO - PID 9764 | 16:15:22 INFO - PID 9764 | [#1] ext.html Cycles:180 Average:37588.04 Median:3584.33 stddev:54522.03 (1521.1%) stddev-sans-first:54605.31 16:15:22 INFO - PID 9764 | Values: 882.4 886.7 887.7 893.2 916.9 39877.2 40302.6 40486.4 40539.0 40566.0 37230.0 37508.8 37548.4 37570.8 37717.0 7122.4 7159.1 7256.5 7258.6 7287.3 7103.7 7164.7 7211.3 7228.3 7234.6 7668.0 7918.8 7919.1 7962.1 8009.5 7889.6 7895.4 7927.4 7930.4 7945.2 7907.7 7986.7 7992.0 7994.0 7998.9 37887.4 38080.2 38199.4 38289.8 38349.6 3547.2 3584.1 3584.6 3587.0 3595.2 30925.8 31095.8 31104.5 31374.7 31449.4 5209.5 5231.8 5254.3 5254.4 5290.2 189143.5 191908.0 193189.0 193879.0 194316.0 48564.5 48695.8 48745.5 48848.3 49086.0 50415.5 50527.2 50598.7 50651.0 50661.2 14546.8 14827.8 14830.6 14873.0 14886.3 12032.8 12060.2 12082.5 12083.7 12097.4 13864.4 13946.3 14105.6 14219.1 14242.1 5279.4 5371.6 5413.8 5502.0 5505.6 995.7 1001.5 1021.2 1024.4 1026.8 22466.6 22795.0 22804.8 22808.3 22964.9 1104.6 1108.4 1108.8 1117.6 1120.6 12068.3 12153.4 12202.3 12240.8 12259.7 228310.0 229043.0 229640.0 229645.0 229897.0 44164.0 44779.3 50722.5 51289.0 51666.5 1432.5 1456.6 1456.9 1470.4 1473.7 27041.5 27233.3 27724.4 27734.9 27785.2 37619.6 37660.8 37663.0 37664.2 37723.0 206502.0 208010.0 208498.0 208816.0 208858.0 32388.4 33464.7 33479.7 33738.5 33914.7 42615.0 42660.4 43187.0 43216.0 43293.6 30897.0 31246.0 31412.5 31498.7 31668.5 31383.4 31708.5 31717.8 31739.7 31938.0 36137.8 36170.1 36393.5 36446.8 36483.5 30880.4 31146.7 31281.2 31374.4 31835.2 22266.8 23183.0 23204.0 23263.4 23558.6 16:15:22 INFO - PID 9764 | 16:15:22 INFO - PID 9764 | [#2] jquery.html Cycles:180 Average:34690.69 Median:23044.10 stddev:83575.86 (362.7%) stddev-sans-first:83771.07 16:15:22 INFO - PID 9764 | Values: 579.8 585.3 590.4 595.6 619.4 21951.2 22678.0 22707.7 22723.0 22727.0 23419.5 24243.3 24285.0 24392.3 24502.4 6376.3 6571.3 6572.3 6581.6 6583.6 6359.8 6436.5 6480.6 6514.1 6516.3 6609.2 6609.6 6638.0 6644.9 6649.7 6600.5 6611.0 6613.2 6614.9 6638.9 5841.6 6043.2 6062.6 6128.2 6168.9 27131.5 27606.9 27695.7 27798.0 27816.2 2038.6 2043.3 2055.8 2057.1 2060.1 18804.1 19379.8 19670.1 19688.7 19709.7 2466.3 2488.1 2524.1 2524.4 2525.9 124221.4 131501.3 132211.0 138793.0 144316.0 14658.2 14750.1 14892.3 14973.7 15230.0 23042.4 23045.8 23079.8 23130.2 23144.4 9701.7 9755.1 9785.5 9836.5 9869.2 8903.0 8909.9 9020.2 9021.0 9026.1 9163.7 9268.4 9272.4 9280.3 9364.3 2674.0 2755.8 2776.6 2835.8 2881.3 949.9 978.4 982.5 986.9 988.6 18371.7 18387.2 18729.4 19012.0 19445.0 1036.9 1038.2 1051.6 1063.5 1067.0 10367.3 10406.3 10496.8 10523.1 10776.1 468091.0 493344.0 500609.0 510298.0 510622.0 112428.0 113701.4 114280.0 114528.0 117616.4 1139.3 1192.4 1235.4 1258.0 1259.1 19812.7 19875.8 20123.0 20516.1 20528.2 58966.7 59789.0 60134.4 60263.4 61217.4 71142.3 71689.0 72056.6 74618.3 77477.6 13872.2 14174.9 14192.9 14218.3 14269.7 21128.5 21253.4 21501.4 21674.2 21691.6 16459.8 16727.2 16806.8 16814.7 16828.5 15092.4 15615.0 15855.2 16337.1 16379.2 16882.9 17621.9 17769.9 18161.5 18176.0 13908.0 13990.5 14052.6 14071.4 14094.1 17280.3 17335.7 17368.8 17458.8 17546.8 16:15:22 INFO - PID 9764 | 16:15:22 INFO - PID 9764 | [#3] mootools.html Cycles:180 Average:9523.23 Median:2694.99 stddev:6758.48 (250.8%) stddev-sans-first:6749.55 16:15:22 INFO - PID 9764 | Values: 1351.9 1401.6 1417.3 1420.8 1428.1 14980.0 15706.6 15712.0 15712.5 15720.1 15385.0 15461.8 15555.1 15569.3 15591.6 2787.4 2793.5 2835.7 2838.5 2847.1 2678.2 2716.9 2727.7 2755.9 2761.5 2719.5 2790.2 2876.5 2877.5 2886.6 2853.4 2858.4 2874.8 2876.1 2879.8 2682.8 2707.2 2712.4 2716.5 2728.0 14690.9 14863.1 14924.4 15015.2 15060.2 8181.5 8453.0 8474.5 8521.5 8536.9 5286.3 5333.0 5455.8 5533.0 5569.7 2010.2 2011.5 2014.4 2031.1 2051.8 26512.7 26621.7 26644.3 26666.0 26744.2 6963.9 6982.5 7078.9 7157.6 7186.3 17493.7 17589.6 17590.8 17597.2 17608.1 8168.9 8336.5 8347.2 8360.5 8388.3 7271.7 7371.7 7453.7 7482.1 7525.6 5226.7 5400.4 5411.6 5435.3 5471.0 1403.2 1411.3 1561.0 1587.3 1594.4 891.1 906.8 909.5 918.7 920.8 8522.2 8529.4 8690.4 8796.5 8825.4 952.8 974.9 976.2 990.2 1012.9 5296.4 5361.6 5503.2 5511.7 5576.9 21976.8 22537.9 22833.9 22849.8 23017.4 20929.8 22167.8 22473.3 22588.7 22720.8 11099.0 11213.3 11223.3 11257.1 11272.8 8194.9 8485.0 8566.4 8701.2 8721.4 18099.3 18192.9 18972.4 19196.4 19265.3 19084.5 19162.8 19182.5 19305.8 19340.3 10659.3 10690.6 10939.9 10941.7 10984.7 13945.1 14016.0 14092.1 14094.3 14164.4 10132.5 10564.6 11610.9 11784.5 11799.3 11272.5 11364.9 11375.2 11432.3 11709.0 9867.9 9886.1 10144.0 10163.4 10193.1 7787.6 7866.7 7874.6 7881.3 7930.2 7574.3 7678.2 7681.3 7705.8 7813.8 16:15:22 INFO - PID 9764 | 16:15:22 INFO - PID 9764 | [#4] prototype.html Cycles:180 Average:5694.67 Median:6305.93 stddev:1517.84 (24.1%) stddev-sans-first:1471.22 16:15:22 INFO - PID 9764 | Values: 502.7 531.9 537.4 537.9 542.7 6134.2 6144.4 6228.7 6345.7 6440.9 6289.9 6297.7 6314.2 6327.5 6354.1 5182.1 5221.2 5265.7 5295.2 5295.8 5073.5 5259.3 5315.9 5328.7 5334.8 5305.3 5316.4 5317.8 5321.1 5363.6 5245.1 5261.9 5273.3 5310.6 5361.9 4968.6 4988.8 5033.8 5034.1 5059.4 6318.9 6335.3 6336.3 6378.7 6392.6 5904.7 6033.3 6046.2 6077.3 6092.8 6664.0 6709.3 6754.6 6761.7 6778.5 5406.8 5415.9 5422.6 5439.8 5467.6 7008.8 7048.3 7062.6 7062.7 7062.8 5603.1 5620.4 5629.1 5635.9 5639.9 5661.2 5685.4 5721.0 5735.4 5744.5 6064.5 6090.1 6100.4 6102.0 6115.2 6435.7 6743.7 6799.9 6818.8 6831.4 1852.2 1882.8 1906.8 1940.3 1961.5 1426.4 1440.4 1441.2 1467.6 1493.6 5701.5 5760.7 5786.3 5788.9 5844.2 6537.0 6662.0 6673.9 6754.8 6767.2 5689.0 5698.3 5765.3 5827.2 5838.0 3551.2 3580.7 3622.2 3633.2 3655.9 6552.4 6553.8 6576.9 6585.0 6668.7 6946.9 6959.9 6977.3 7016.6 7031.2 6622.8 6630.6 6675.3 6686.4 6686.8 6810.6 6837.9 6868.5 6873.7 6906.2 6731.6 6749.3 6768.6 6806.6 6865.6 6523.6 6558.1 6575.7 6581.5 6614.1 5064.6 5085.2 5094.6 5137.7 5144.4 6516.4 6710.6 6729.6 6733.0 6857.6 6236.2 6402.4 6466.0 6484.8 6493.2 6475.2 6542.8 6560.2 6613.5 6624.5 6455.3 6461.5 6542.9 6546.2 6567.3 6479.4 6535.8 6602.9 6674.0 6720.1 6435.4 6439.0 6455.3 6465.5 6471.9 16:15:22 INFO - PID 9764 | 16:15:22 INFO - PID 9764 | [#5] yui.html Cycles:180 Average:24273.95 Median:20555.09 stddev:34844.39 (169.5%) stddev-sans-first:34897.94 16:15:22 INFO - PID 9764 | Values: 900.4 917.8 937.2 942.9 953.9 19629.6 20346.0 20347.4 20375.7 20392.3 18796.3 18823.2 19023.9 19564.8 19708.3 961.6 974.5 977.9 978.7 979.3 966.6 980.8 984.5 990.3 1006.8 1048.5 1049.9 1050.3 1051.4 1051.4 990.2 1012.4 1028.0 1036.9 1041.8 746.0 746.7 753.1 753.9 754.8 20468.3 20641.9 20792.9 20802.4 20813.4 21140.9 21429.1 21436.9 21536.8 21564.4 17519.0 17590.7 18212.7 18373.1 18387.7 3825.5 3851.9 3855.7 3857.1 3858.5 133891.3 134102.0 134182.0 134312.0 136113.3 16667.7 16946.6 16962.0 17078.1 17212.8 30236.5 31112.7 31146.1 31449.2 31467.7 8892.4 8893.7 8922.4 8935.1 8974.5 4278.5 4346.5 4436.7 4448.4 4477.1 5365.7 5484.3 5493.3 5498.6 5504.1 957.7 972.6 982.4 987.4 991.5 688.7 713.3 717.9 720.5 720.8 12120.7 12136.4 12237.8 12585.3 13013.8 632.4 639.4 647.0 648.7 649.7 5737.0 5754.6 5775.7 5913.5 5955.8 119224.4 120392.0 121423.4 121682.0 123574.4 108464.5 108857.5 108926.5 111910.4 112035.0 92348.5 93123.5 93344.5 95290.0 96341.0 10836.1 10846.8 10969.3 11012.0 11165.5 45052.5 45621.3 45628.3 46164.5 47254.3 59240.0 59715.4 59938.4 60065.7 62161.4 15048.2 15471.5 15494.5 15549.2 15647.2 16332.4 16563.2 16614.3 17013.6 17014.7 14581.0 14648.8 14681.3 14791.6 14935.8 15821.6 16040.7 16044.3 16094.3 16167.0 14727.6 14871.8 14913.8 15014.0 15036.2 11465.7 11631.7 11647.4 11784.8 11859.2 10581.3 10703.4 10748.2 10889.2 11006.0 16:15:22 INFO - PID 9764 | -------- Summary: end -------- 16:15:22 INFO - PID 9764 | 16:15:23 INFO - TEST-INFO | 9764: exit 0 16:15:23 INFO - TEST-OK | dromaeo_css | took 1109404ms 16:15:23 INFO - TEST-START | kraken 16:15:23 INFO - Initialising browser for kraken test... 16:15:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55199/getInfo.html -profile /tmp/tmph_3rBj/profile 16:15:23 INFO - TEST-INFO | started process 10144 (/builds/slave/test/build/application/firefox/firefox http://localhost:55199/getInfo.html) 16:15:29 INFO - TEST-INFO | 10144: exit 0 16:15:29 INFO - Browser initialized. 16:15:29 INFO - Running cycle 1/1 for kraken test... 16:15:29 INFO - Using env: {'DISPLAY': ':0', 16:15:29 INFO - 'HOME': '/home/cltbld', 16:15:29 INFO - 'JSGC_DISABLE_POISONING': '1', 16:15:29 INFO - 'LANG': 'en_US.UTF-8', 16:15:29 INFO - 'LANGUAGE': 'en_US:en', 16:15:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:15:29 INFO - 'LOGNAME': 'cltbld', 16:15:29 INFO - 'MAIL': '/var/mail/cltbld', 16:15:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:15:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:15:29 INFO - 'MOZ_CRASHREPORTER': '1', 16:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:15:29 INFO - 'MOZ_NO_REMOTE': '1', 16:15:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:15:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:29 INFO - 'NO_EM_RESTART': '1', 16:15:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:29 INFO - 'PWD': '/builds/slave/test', 16:15:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:15:29 INFO - 'RUST_BACKTRACE': 'full', 16:15:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:15:29 INFO - 'SHELL': '/bin/bash', 16:15:29 INFO - 'SHLVL': '1', 16:15:29 INFO - 'TERM': 'linux', 16:15:29 INFO - 'TMOUT': '86400', 16:15:29 INFO - 'USER': 'cltbld', 16:15:29 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012', 16:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:29 INFO - '_': '/tools/buildbot/bin/python'} 16:15:29 INFO - TEST-INFO | started process 10382 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmph_3rBj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:15:30 INFO - PID 10382 | 16:15:30 INFO - PID 10382 | (/builds/slave/test/build/application/firefox/firefox:10431): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:15:30 INFO - PID 10382 | 16:15:40 INFO - PID 10382 | RSS: Main: 151273472 16:15:40 INFO - PID 10382 | 16:15:43 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55199/tests/kraken/driver.html?testName=audio-beat-detection) 16:15:44 INFO - PID 10382 | RSS: Main: 157249536 16:15:44 INFO - PID 10382 | 16:15:46 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55199/tests/kraken/driver.html?testName=audio-dft) 16:15:47 INFO - PID 10382 | RSS: Main: 157986816 16:15:47 INFO - PID 10382 | 16:15:50 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55199/tests/kraken/driver.html?testName=audio-fft) 16:15:51 INFO - PID 10382 | RSS: Main: 155201536 16:15:51 INFO - PID 10382 | 16:15:53 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55199/tests/kraken/driver.html?testName=audio-oscillator) 16:15:53 INFO - PID 10382 | RSS: Main: 155197440 16:15:53 INFO - PID 10382 | 16:15:55 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55199/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:15:56 INFO - PID 10382 | RSS: Main: 155697152 16:15:56 INFO - PID 10382 | 16:15:59 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55199/tests/kraken/driver.html?testName=imaging-darkroom) 16:16:00 INFO - PID 10382 | RSS: Main: 158322688 16:16:00 INFO - PID 10382 | 16:16:04 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55199/tests/kraken/driver.html?testName=imaging-desaturate) 16:16:04 INFO - PID 10382 | RSS: Main: 158089216 16:16:04 INFO - PID 10382 | 16:16:07 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55199/tests/kraken/driver.html?testName=json-parse-financial) 16:16:08 INFO - PID 10382 | RSS: Main: 158330880 16:16:08 INFO - PID 10382 | 16:16:09 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55199/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:16:10 INFO - PID 10382 | RSS: Main: 158334976 16:16:10 INFO - PID 10382 | 16:16:12 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55199/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:16:12 INFO - PID 10382 | RSS: Main: 155525120 16:16:12 INFO - PID 10382 | 16:16:14 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55199/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:16:15 INFO - PID 10382 | RSS: Main: 155394048 16:16:15 INFO - PID 10382 | 16:16:18 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55199/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:16:18 INFO - PID 10382 | RSS: Main: 155709440 16:16:18 INFO - PID 10382 | 16:16:21 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55199/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:16:22 INFO - PID 10382 | RSS: Main: 155914240 16:16:22 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | Cycle 1(1): loaded http://localhost:55199/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55199/tests/kraken/driver.html?testName=ai-astar) 16:16:24 INFO - PID 10382 | RSS: Main: 157097984 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | __start_tp_report 16:16:24 INFO - PID 10382 | _x_x_mozilla_page_load 16:16:24 INFO - PID 10382 | _x_x_mozilla_page_load_details 16:16:24 INFO - PID 10382 | |i|pagename|runs| 16:16:24 INFO - PID 10382 | |0;ai-astar;108;107;107;107;106;108;107;107;105;106 16:16:24 INFO - PID 10382 | |1;audio-beat-detection;117;118;120;117;125;116;117;143;122;117 16:16:24 INFO - PID 10382 | |2;audio-dft;163;163;163;164;162;161;163;164;169;164 16:16:24 INFO - PID 10382 | |3;audio-fft;75;79;73;73;73;74;74;79;111;74 16:16:24 INFO - PID 10382 | |4;audio-oscillator;80;81;80;79;79;78;78;78;79;79 16:16:24 INFO - PID 10382 | |5;imaging-gaussian-blur;114;113;114;113;114;115;114;113;114;115 16:16:24 INFO - PID 10382 | |6;imaging-darkroom;148;149;151;151;149;148;149;152;151;151 16:16:24 INFO - PID 10382 | |7;imaging-desaturate;99;99;94;97;96;95;95;95;96;96 16:16:24 INFO - PID 10382 | |8;json-parse-financial;53;55;55;53;55;57;55;57;52;52 16:16:24 INFO - PID 10382 | |9;json-stringify-tinderbox;38;38;38;40;37;38;38;38;38;38 16:16:24 INFO - PID 10382 | |10;stanford-crypto-aes;81;76;74;74;74;74;74;75;75;75 16:16:24 INFO - PID 10382 | |11;stanford-crypto-ccm;119;120;118;111;115;113;115;117;116;112 16:16:24 INFO - PID 10382 | |12;stanford-crypto-pbkdf2;173;184;170;172;164;169;167;174;168;179 16:16:24 INFO - PID 10382 | |13;stanford-crypto-sha256-iterative;71;66;69;67;65;65;68;69;63;65 16:16:24 INFO - PID 10382 | __end_tp_report 16:16:24 INFO - PID 10382 | __start_cc_report 16:16:24 INFO - PID 10382 | _x_x_mozilla_cycle_collect,765 16:16:24 INFO - PID 10382 | __end_cc_report 16:16:24 INFO - PID 10382 | __startTimestamp1506122184377__endTimestamp 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | ------- Summary: start ------- 16:16:24 INFO - PID 10382 | Number of tests: 14 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#0] ai-astar Cycles:10 Average:106.80 Median:107.00 stddev:0.92 (0.9%) stddev-sans-first:0.87 16:16:24 INFO - PID 10382 | Values: 108.0 107.0 107.0 107.0 106.0 108.0 107.0 107.0 105.0 106.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#1] audio-beat-detection Cycles:10 Average:121.20 Median:119.00 stddev:8.16 (6.9%) stddev-sans-first:8.51 16:16:24 INFO - PID 10382 | Values: 117.0 118.0 120.0 117.0 125.0 116.0 117.0 143.0 122.0 117.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.50 stddev:2.12 (1.3%) stddev-sans-first:2.24 16:16:24 INFO - PID 10382 | Values: 163.0 163.0 163.0 164.0 162.0 161.0 163.0 164.0 169.0 164.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#3] audio-fft Cycles:10 Average:78.50 Median:74.00 stddev:11.65 (15.7%) stddev-sans-first:12.28 16:16:24 INFO - PID 10382 | Values: 75.0 79.0 73.0 73.0 73.0 74.0 74.0 79.0 111.0 74.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#4] audio-oscillator Cycles:10 Average:79.10 Median:79.00 stddev:0.99 (1.3%) stddev-sans-first:1.00 16:16:24 INFO - PID 10382 | Values: 80.0 81.0 80.0 79.0 79.0 78.0 78.0 78.0 79.0 79.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 16:16:24 INFO - PID 10382 | Values: 114.0 113.0 114.0 113.0 114.0 115.0 114.0 113.0 114.0 115.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:151.00 stddev:1.45 (1.0%) stddev-sans-first:1.36 16:16:24 INFO - PID 10382 | Values: 148.0 149.0 151.0 151.0 149.0 148.0 149.0 152.0 151.0 151.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:1.69 (1.8%) stddev-sans-first:1.45 16:16:24 INFO - PID 10382 | Values: 99.0 99.0 94.0 97.0 96.0 95.0 95.0 95.0 96.0 96.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:1.84 (3.3%) stddev-sans-first:1.88 16:16:24 INFO - PID 10382 | Values: 53.0 55.0 55.0 53.0 55.0 57.0 55.0 57.0 52.0 52.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.74 (1.9%) stddev-sans-first:0.78 16:16:24 INFO - PID 10382 | Values: 38.0 38.0 38.0 40.0 37.0 38.0 38.0 38.0 38.0 38.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:75.00 stddev:2.15 (2.9%) stddev-sans-first:0.73 16:16:24 INFO - PID 10382 | Values: 81.0 76.0 74.0 74.0 74.0 74.0 74.0 75.0 75.0 75.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#11] stanford-crypto-ccm Cycles:10 Average:115.60 Median:116.50 stddev:2.99 (2.6%) stddev-sans-first:2.91 16:16:24 INFO - PID 10382 | Values: 119.0 120.0 118.0 111.0 115.0 113.0 115.0 117.0 116.0 112.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:172.50 stddev:5.93 (3.4%) stddev-sans-first:6.27 16:16:24 INFO - PID 10382 | Values: 173.0 184.0 170.0 172.0 164.0 169.0 167.0 174.0 168.0 179.0 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:2.44 (3.6%) stddev-sans-first:2.06 16:16:24 INFO - PID 10382 | Values: 71.0 66.0 69.0 67.0 65.0 65.0 68.0 69.0 63.0 65.0 16:16:24 INFO - PID 10382 | -------- Summary: end -------- 16:16:24 INFO - PID 10382 | 16:16:24 INFO - PID 10382 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:16:24 INFO - TEST-INFO | 10382: exit 0 16:16:25 INFO - TEST-OK | kraken | took 61453ms 16:16:25 INFO - SUITE-END | took 1170s 16:16:25 INFO - Completed test suite (00:19:31) 16:16:25 INFO - javascript benchmark 16:16:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8576.211420715275, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3126.71859532642, 3145.370925814836, 3151.7005884440982, 3155.463571192897, 3156.1478400831775, 6165.784197553086, 6236.407541283571, 6256.84357891053, 6270.4670103041235, 6377.713002914868, 6296.307406185316, 6314.6842657867155, 6358.205224346957, 6402.391772781578, 6409.038644203371, 5596.916046259309, 5673.659580425167, 5808.82573522795, 5809.215755872945, 5873.030949893284, 5726.541876649879, 5778.884422311572, 5780.653160810365, 5823.184754134425, 5832.445917637818, 5663.0655941769555, 5697.715114244271, 5752.252207213078, 5756.827295181151, 5766.769329226847, 5628.943710562883, 5728.598998070137, 5747.971260143672, 5760.567957403212, 5793.768249270024, 5748.7125643717645, 5757.366689664133, 5807.535397168216, 5821.15593238978, 5851.297844258674, 6115.143879856823, 6245.406686364788, 6253.749850005995, 6256.0615907613765, 6278.811635650892, 6694.799156025327, 6777.525573209878, 6797.6601169941305, 6804.285550017276, 6826.112605361321, 6758.594484330956, 6764.222114456851, 6839.0425340452375, 6857.382835544775, 6865.691043903036, 6074.51403887688, 6208.72060757267, 6224.346443623445, 6276.466500347476, 6357.332480089616, 6838.658067096725, 6860.5197636165985, 6883.27725588816, 6896.241413444517, 6921.169459664772, 6567.8358041048405, 6694.230163531159, 6707.5304728668525, 6716.496262780311, 6724.125863637696, 6730.427913627396, 6733.966330168318, 6815.352541508549, 6816.352446517573, 6823.38589526935, 6540.05169250456, 6618.437432818168, 6703.363180497845, 6745.797626071226, 6810.216825065082, 6637.0, 6671.032700258435, 6762.661866906635, 6773.491988100912, 6780.864382712318, 5281.181416880389, 5285.894282114413, 5322.654027488201, 5361.758720857571, 5365.892682146336, 4618.099470603267, 4655.2784318430695, 4670.322803193518, 4675.485696573374, 4686.601638860735, 5676.460736230051, 5782.710864456761, 5783.768649253982, 5792.652440853562, 5870.383609721003, 6574.5397822152945, 6604.768833090819, 6737.056812046318, 6820.556663816836, 6826.863462730717, 5790.652560846278, 5790.942090579125, 5808.332041815245, 5825.24271844666, 5861.501772349398, 5750.913736293959, 5772.191893134965, 5777.24895763548, 5828.708564571754, 5854.824355269348, 6392.904106438407, 6452.774152904626, 6490.350964903472, 6495.155629768195, 6604.075429439882, 6607.933920660828, 6640.402363787282, 6640.966795165993, 6642.335766423319, 6677.198736151693, 6443.903341449788, 6459.483241340776, 6490.480761538971, 6556.508261880283, 6571.310012448624, 6608.372204640552, 6687.799366019027, 6697.866042679022, 6704.765333213314, 6710.194776626836, 6755.223149337692, 6758.155230596176, 6786.355296246848, 6839.452843772387, 6850.520463567503, 6369.49044076482, 6486.2216534016225, 6487.610743355225, 6592.604443733391, 6598.703058362289, 6534.444572211225, 6564.113844630913, 6564.80305590833, 6566.868662626625, 6567.540272180901, 6342.873142537216, 6424.325445828307, 6448.548601597843, 6464.709088091141, 6490.286068532552, 6415.166028416276, 6480.449161821298, 6507.609543427409, 6580.374864387875, 6583.604983700993, 6246.031865060922, 6310.274318453253, 6337.176167098248, 6341.143945567474, 6398.456131228898], "value": 6123.043466951673, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [882.3558802074485, 886.667499687617, 887.6582515731442, 893.1827377949177, 916.8670542771298, 39877.2024559509, 40302.59697403025, 40486.392704109465, 40539.0, 40566.0, 37230.0, 37508.81245593768, 37548.43677344835, 37570.812145939235, 37717.0, 7122.394596459294, 7159.105111861018, 7256.4920455568135, 7258.600776957292, 7287.2712728726965, 7103.68033438494, 7164.7134114635355, 7211.278872112773, 7228.349448549629, 7234.565926044428, 7667.961660191691, 7918.80202994924, 7919.089304729943, 7962.084360298553, 8009.519428834259, 7889.644965976545, 7895.407844411692, 7927.445078844483, 7930.405219608524, 7945.165757595427, 7907.683692652345, 7986.6805327786815, 7992.0, 7994.0, 7998.920010799876, 37887.431688524695, 38080.23839523222, 38199.427008594896, 38289.80855095734, 38349.616503834884, 3547.219611685425, 3584.068142282999, 3584.5877724061806, 3586.9820650896577, 3595.209054008114, 30925.845370773226, 31095.84452077748, 31104.533431998538, 31374.686253137406, 31449.37101257985, 5209.453007434203, 5231.764570594294, 5254.343207099113, 5254.395744489394, 5290.1800220965815, 189143.4885430159, 191908.0, 193189.0, 193879.0, 194316.0, 48564.51435485671, 48695.756521217874, 48745.51254487481, 48848.26727599089, 49086.0, 50415.49584504108, 50527.24209136866, 50598.73503162451, 50651.0, 50661.240081398806, 14546.781798273034, 14827.77758333626, 14830.629234269014, 14873.0, 14886.330115144845, 12032.81950770739, 12060.21608595456, 12082.516699332104, 12083.697907552381, 12097.395130243629, 13864.445422183, 13946.302684865715, 14105.576832695035, 14219.075760075562, 14242.07426517273, 5279.366476022902, 5371.6239863209985, 5413.810516631899, 5502.0, 5505.642133261325, 995.6913356859396, 1001.5142655811925, 1021.2238698588977, 1024.4007255755319, 1026.8305729554688, 22466.55066898653, 22795.0, 22804.771952280265, 22808.315750527512, 22964.885175574014, 1104.5857803323754, 1108.4291589831244, 1108.778244351133, 1117.5641499815079, 1120.590984290728, 12068.275903445645, 12153.392330383445, 12202.267863928193, 12240.81638775419, 12259.69350766238, 228310.0, 229043.0, 229640.0, 229645.0, 229897.0, 44164.0, 44779.328310075376, 50722.49277507252, 51289.0, 51666.48333516692, 1432.5129455985016, 1456.5848733111013, 1456.8761655259423, 1470.3897882378692, 1473.7273604383224, 27041.459170816866, 27233.31916702098, 27724.445511090067, 27734.861325693244, 27785.166445006682, 37619.62380376161, 37660.811695941346, 37663.0, 37664.246715065, 37723.0, 206502.0, 208010.0, 208498.0, 208816.0, 208858.0, 32388.35223295568, 33464.665353347125, 33479.66520334766, 33738.49392259069, 33914.66085339214, 42615.0, 42660.36009459923, 43187.0, 43216.0, 43293.567064328956, 30897.0, 31246.0, 31412.528812068293, 31498.685013149574, 31668.5249721259, 31383.372332552764, 31708.524372133976, 31717.841410792797, 31739.682603174595, 31938.0, 36137.81931090433, 36170.09574760652, 36393.454098189075, 36446.817765911, 36483.45274820827, 30880.382392352476, 31146.688533115284, 31281.217969550944, 31374.37251255008, 31835.20411989719, 22266.777332227117, 23183.0, 23204.0, 23263.41841453977, 23558.646620300366], "value": 17023.87257522688, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.7709904587688, 585.3151812379516, 590.4302348233954, 595.565237376715, 619.4115590189319, 21951.231706890263, 22678.0, 22707.659385109237, 22723.0, 22726.97728602215, 23419.5316093678, 24243.27270181893, 24285.0, 24392.268231953025, 24502.387440313956, 6376.330485299047, 6571.3428657134145, 6572.309907459719, 6581.5721978071515, 6583.604983700993, 6359.8410039748915, 6436.517261205405, 6480.578762380454, 6514.088027676129, 6516.250631177404, 6609.173853268341, 6609.570377925444, 6637.966810165943, 6644.93355066448, 6649.734010639569, 6600.504962127836, 6611.008348747679, 6613.239477460082, 6614.900776488356, 6638.86722265552, 5841.620294680874, 6043.153958445821, 6062.636241825505, 6128.203333566607, 6168.938310616882, 27131.45737085267, 27606.861965690245, 27695.723042769518, 27798.0, 27816.16551503458, 2038.6024725178597, 2043.3052762060822, 2055.825254853339, 2057.1154403606442, 2060.124447109972, 18804.059797010093, 19379.80620193794, 19670.114844832016, 19688.704669429826, 19709.704354434696, 2466.3464181991785, 2488.054539275082, 2524.129175434472, 2524.394145405108, 2525.936851578707, 124221.37889310587, 131501.34249328787, 132211.0, 138793.0, 144316.0, 14658.207898175657, 14750.114993100447, 14892.255387230813, 14973.700525989638, 15230.0, 23042.423939401648, 23045.769542304697, 23079.76920230776, 23130.190443334403, 23144.42138946506, 9701.6604418845, 9755.073268039667, 9785.510724463747, 9836.458994755214, 9869.161121304625, 8903.0, 8909.866352004596, 9020.233280171127, 9020.954895225483, 9026.052264512264, 9163.725088247364, 9268.397554158959, 9272.350935434455, 9280.257579393632, 9364.25085993119, 2673.9598906016427, 2755.8484283364205, 2776.5557510798176, 2835.844028578448, 2881.3228891210547, 949.928755343352, 978.4373984958677, 982.513655740413, 986.9358491698017, 988.6440881282732, 18371.72442413365, 18387.161283871774, 18729.438116856516, 19012.0, 19445.0, 1036.9377837329785, 1038.1746511523206, 1051.621416290135, 1063.5214153630898, 1067.0396643021343, 10367.32612380193, 10406.323588966692, 10496.790064198673, 10523.052925236765, 10776.137908967265, 468091.0, 493344.0, 500609.0, 510298.0, 510622.0, 112428.0, 113701.431492842, 114280.0, 114528.0, 117616.41191793987, 1139.2765593847967, 1192.3859212505567, 1235.3576140407117, 1257.9559715409916, 1259.1311994723794, 19812.70280945816, 19875.801241987396, 20123.0, 20516.076776544793, 20528.178872844917, 58966.70516647389, 59789.0, 60134.39865601463, 60263.397366025776, 61217.38782612117, 71142.28857711564, 71689.0, 72056.63971680107, 74618.25381746113, 77477.61261193884, 13872.23702696321, 14174.858251417354, 14192.929035354757, 14218.289085545475, 14269.71460570762, 21128.471788205417, 21253.36239912805, 21501.354959351243, 21674.24140155087, 21691.566168676854, 16459.83540164583, 16727.163641818104, 16806.83193168053, 16814.747778783574, 16828.495145145665, 15092.39630414771, 15615.0, 15855.20723963774, 16337.101459419371, 16379.181040947667, 16882.915585421993, 17621.911890440464, 17769.911150444164, 18161.545961350545, 18176.0, 13908.0, 13990.510332138327, 14052.578422647337, 14071.366788494746, 14094.083884547472, 17280.308787648355, 17335.653286934445, 17368.82631173672, 17458.825411746227, 17546.82453175452], "value": 11855.506789334984, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1351.8512963574005, 1401.5935378740166, 1417.3267697843526, 1420.8366037905644, 1428.0860249440357, 14980.0, 15706.607334816636, 15711.978721383102, 15712.52862414128, 15720.056796592213, 15385.0, 15461.768073478906, 15555.144467054308, 15569.29938152783, 15591.610209744762, 2787.414642924983, 2793.4971705093076, 2835.7022512636186, 2838.489071967045, 2847.117393607988, 2678.1563807400607, 2716.945661086787, 2727.740864617868, 2755.8622068896475, 2761.4753196892525, 2719.5104881121392, 2790.1768978151395, 2876.4822331980236, 2877.5252083406212, 2886.552584349419, 2853.386521897802, 2858.4426036922705, 2874.798764086515, 2876.1084063940143, 2879.7840161987933, 2682.785377169822, 2707.2013755942044, 2712.4303896181827, 2716.456708658276, 2727.986360068207, 14690.926545367312, 14863.108213507225, 14924.403023879031, 15015.249237538079, 15060.171690557016, 8181.468204566684, 8452.957735211347, 8474.533900635462, 8521.531315777629, 8536.8719469208, 5286.259923610698, 5333.0, 5455.754491047913, 5532.972335138339, 5569.66582005081, 2010.1657812008145, 2011.517235863397, 2014.4359579317668, 2031.0758604834764, 2051.7640471345835, 26512.73487265122, 26621.73378266231, 26644.33389165267, 26666.0, 26744.19767406981, 6963.9303606963285, 6982.476314276348, 7078.893816592756, 7157.57054576727, 7186.317299856505, 17493.65012699739, 17589.64820703579, 17590.824091759176, 17597.208125634377, 17608.11959402025, 8168.877466838002, 8336.541490218096, 8347.248747612744, 8360.498370097814, 8388.28699560532, 7271.67277472515, 7371.741989030358, 7453.664585093684, 7482.064741907262, 7525.58609276495, 5226.660267082628, 5400.432954539795, 5411.648242864201, 5435.347758269033, 5470.972645136829, 1403.2282244765338, 1411.2943528235883, 1560.9921950390176, 1587.2540205773557, 1594.4419453191522, 891.1133422244778, 906.7823722306599, 909.5497728624373, 918.7335672654855, 920.7559996600907, 8522.190391912882, 8529.360297977677, 8690.391672582986, 8796.47221166732, 8825.426347287406, 952.7760976170595, 974.8927617962019, 976.1848856205148, 990.1633120013577, 1012.9037741414554, 5296.443873393238, 5361.597880158925, 5503.229547863302, 5511.696856672841, 5576.888462230814, 21976.780232197794, 22537.887310563343, 22833.885830571075, 22849.771502285097, 23017.424564386023, 20929.790702093087, 22167.778322216895, 22473.32580022602, 22588.66117008246, 22720.772792272197, 11099.001089901947, 11213.327200368005, 11223.270487418455, 11257.099432045417, 11272.830907536394, 8194.877076843972, 8484.957575212084, 8566.443181193203, 8701.216890479634, 8721.433106848288, 18099.276028958695, 18192.909035454737, 18972.430827075208, 19196.424107276805, 19265.325713599956, 19084.473242140317, 19162.808371916104, 19182.520436989187, 19305.80694193096, 19340.323088692392, 10659.307145035547, 10690.62582809598, 10939.890601093886, 10941.671749847517, 10984.670459886216, 13945.093568917988, 14016.0, 14092.084014539023, 14094.295285235492, 14164.362603682654, 10132.544035518273, 10564.577416903237, 11610.94194529022, 11784.528618855493, 11799.292042477478, 11272.549098036316, 11364.886351136383, 11375.20373573842, 11432.256903301259, 11709.0, 9867.851982220129, 9886.11025007724, 10144.0, 10163.390196588229, 10193.133583645176, 7787.5716835574585, 7866.72466463671, 7874.645640946285, 7881.330086942674, 7930.246626570467, 7574.280443357872, 7678.1937896522295, 7681.270279323456, 7705.80735481595, 7813.804654883674], "value": 6838.9416219007, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.67326237945355, 531.9148936170212, 537.4303238567118, 537.8574677710405, 542.6608369768894, 6134.202553668022, 6144.416280453361, 6228.726514822091, 6345.7461701532, 6440.935590644104, 6289.937100629004, 6297.653629050412, 6314.210723659543, 6327.544664727104, 6354.110424540557, 5182.149817600323, 5221.164613661806, 5265.736713164346, 5295.152775555912, 5295.761690723907, 5073.4926507349155, 5259.31628888244, 5315.920261196085, 5328.653637513549, 5334.786608535654, 5305.336832895888, 5316.362036555619, 5317.813876514323, 5321.088999585301, 5363.570914326844, 5245.082110630643, 5261.947380526203, 5273.314469119028, 5310.575153987672, 5361.892762144775, 4968.552830245259, 4988.750562471898, 5033.798648054073, 5034.09386310464, 5059.443461219263, 6318.87362252757, 6335.271443783978, 6336.271328797201, 6378.68106594673, 6392.616443013388, 5904.671448924, 6033.336333003366, 6046.213992181032, 6077.3314935357075, 6092.756289748405, 6663.966680166616, 6709.295523969961, 6754.628495432749, 6761.661916904184, 6778.508728079814, 5406.810761623364, 5415.918761218585, 5422.647527910713, 5439.782408703687, 5467.589930755209, 7008.824779380505, 7048.295170482962, 7062.611556364398, 7062.6821793019435, 7062.823429414203, 5603.131514615241, 5620.437956204347, 5629.102680667846, 5635.915461268084, 5639.915401268984, 5661.235733176051, 5685.403032681592, 5721.0, 5735.426457354231, 5744.511716504144, 6064.514838812885, 6090.086487026892, 6100.359462256399, 6101.969490152521, 6115.1744514490865, 6435.710393032325, 6743.730250789913, 6799.864002719918, 6818.761343352959, 6831.385175334244, 1852.2313240005517, 1882.8117188281337, 1906.761654793151, 1940.2723978508059, 1961.4507937777446, 1426.3937826423728, 1440.3590402270888, 1441.1929319581068, 1467.6037469883072, 1493.5817970968146, 5701.458361455655, 5760.724513817019, 5786.334571524244, 5788.942110578924, 5844.211031510692, 6536.967315163393, 6661.966690166518, 6673.933260667428, 6754.750371181347, 6767.1879374475375, 5688.9759843227785, 5698.344690360579, 5765.308163020464, 5827.155062515912, 5838.007538718428, 3551.236484155919, 3580.695640870503, 3622.185008373095, 3633.1643721943574, 3655.94516082259, 6552.410283074546, 6553.770618028441, 6576.934230657632, 6585.012248162814, 6668.666566671647, 6946.93053069473, 6959.886418173068, 6977.278861244119, 7016.649167541603, 7031.242189452637, 6622.807894578926, 6630.602163870183, 6675.3324667533825, 6686.364795344434, 6686.83282917931, 6810.569780346183, 6837.931620683729, 6868.484863635147, 6873.656317184221, 6906.171259448898, 6731.629760363227, 6749.257581666111, 6768.593884366954, 6806.591604503746, 6865.622390768555, 6523.64119973406, 6558.147440832852, 6575.704093315717, 6581.4533584606625, 6614.10709554204, 5064.620153488429, 5085.211792172219, 5094.592432605308, 5137.691738495702, 5144.382674078985, 6516.413522782972, 6710.597364158166, 6729.629870357177, 6732.980105968092, 6857.622830744157, 6236.220472440945, 6402.391772781578, 6466.030095485715, 6484.773032943825, 6493.155889734304, 6475.158229430144, 6542.8364290893105, 6560.212774467094, 6613.470922326106, 6624.503162262753, 6455.3221911700475, 6461.483081353575, 6542.901856472062, 6546.214454265519, 6567.343265673395, 6479.449246814073, 6535.771248006297, 6602.88707613559, 6673.9666301668185, 6720.1263835703, 6435.356464353527, 6438.967805160944, 6455.289918108911, 6465.515086368635, 6471.902921456276], "value": 5258.224177075941, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [900.4327273817494, 917.8347897378471, 937.2455173585261, 942.9104235097664, 953.9379940303882, 19629.60740785185, 20346.0, 20347.389578312657, 20375.694364584506, 20392.286269980552, 18796.34212802552, 18823.152958116883, 19023.904880475613, 19564.80435195651, 19708.310209142684, 961.6297725375729, 974.4786539201529, 977.9168770654503, 978.7063880835757, 979.3095867413457, 966.5747071288646, 980.8234517786796, 984.5126662302171, 990.3315262198023, 1006.783541538569, 1048.4757621189406, 1049.8582691336687, 1050.2700623066971, 1051.3796859852685, 1051.4059556350676, 990.1830989433674, 1012.4077414712402, 1027.9897201028007, 1036.8755749310055, 1041.7551875309296, 746.0, 746.660269577341, 753.1188509443964, 753.8906858505541, 754.7999780058288, 20468.283610073577, 20641.8967905161, 20792.896035519876, 20802.375928722162, 20813.375598732062, 21140.89429552858, 21429.14283428661, 21436.89281553598, 21536.78463215379, 21564.35306940794, 17519.0, 17590.736138957927, 18212.726809097872, 18373.08134593265, 18387.72418413725, 3825.5218097737784, 3851.903702407434, 3855.749376290532, 3857.1321452673205, 3858.536975562922, 133891.33054334667, 134102.0, 134182.0, 134312.0, 136113.3194334022, 16667.749983750255, 16946.576335591708, 16962.0, 17078.146092695315, 17212.827871721373, 30236.546451803242, 31112.68887311098, 31146.065618031495, 31449.21376965594, 31467.685323146932, 8892.377533572717, 8893.73318800437, 8922.375433719577, 8935.106489351143, 8974.506402147945, 4278.465191851019, 4346.478422589246, 4436.689431739747, 4448.399466072104, 4477.082198149372, 5365.704886231217, 5484.314460692413, 5493.340799104133, 5498.642588231832, 5504.091824848935, 957.7366224288377, 972.5879818051272, 982.4007355513131, 987.4075554667286, 991.5191132300828, 688.7072993977539, 713.2546488919093, 717.8923161525709, 720.5208536323391, 720.8270015196315, 12120.696982575506, 12136.393180341125, 12237.75524489523, 12585.307808070644, 13013.804792928113, 632.3581564711822, 639.402158981354, 646.9838254043686, 648.672420427681, 649.7206201333378, 5736.971315143481, 5754.5971781975695, 5775.726581015342, 5913.49735272498, 5955.79154729591, 119224.40387798006, 120392.0, 121423.39288303678, 121682.0, 123574.38212809058, 108464.45767771268, 108857.45571272093, 108926.45536772424, 111910.44044779887, 112035.0, 92348.53825730829, 93123.53438232765, 93344.53327733454, 95290.0, 96341.0, 10836.064918452039, 10846.837297440386, 10969.286996345212, 11012.0, 11165.49755260999, 45052.549474504835, 45621.315680265485, 45628.31557526573, 46164.53835461602, 47254.29118563293, 59240.0, 59715.40284597098, 59938.40061599328, 60065.69967150136, 62161.37838621556, 15048.172350520827, 15471.458498952483, 15494.5351639451, 15549.222538872786, 15647.217639118226, 16332.428365007643, 16563.171841408122, 16614.25235864413, 17013.574660633087, 17014.74477882808, 14581.0, 14648.780268295772, 14681.265936702908, 14791.630209244855, 14935.775963360775, 15821.604459888134, 16040.679186415973, 16044.278007489924, 16094.275757591171, 16167.0, 14727.55817325482, 14871.776923346375, 14913.776293355391, 15014.0, 15036.248187590358, 11465.713357166138, 11631.6510504685, 11647.417629118341, 11784.823227651763, 11859.16985810985, 10581.31221470602, 10703.357798532114, 10748.247622666338, 10889.183311251529, 11006.0], "value": 8953.136772722322, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1431.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 107.0, 107.0, 107.0, 106.0, 108.0, 107.0, 107.0, 105.0, 106.0], "value": 106.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 120.0, 117.0, 125.0, 116.0, 117.0, 143.0, 122.0, 117.0], "value": 121.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 164.0, 162.0, 161.0, 163.0, 164.0, 169.0, 164.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 73.0, 73.0, 73.0, 74.0, 74.0, 79.0, 111.0, 74.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 80.0, 79.0, 79.0, 78.0, 78.0, 78.0, 79.0, 79.0], "value": 79.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 114.0, 115.0, 114.0, 113.0, 114.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 151.0, 151.0, 149.0, 148.0, 149.0, 152.0, 151.0, 151.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 94.0, 97.0, 96.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 55.0, 53.0, 55.0, 57.0, 55.0, 57.0, 52.0, 52.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 40.0, 37.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 74.0, 74.0, 74.0, 74.0, 74.0, 75.0, 75.0, 75.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 120.0, 118.0, 111.0, 115.0, 113.0, 115.0, 117.0, 116.0, 112.0], "value": 115.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 184.0, 170.0, 172.0, 164.0, 169.0, 167.0, 174.0, 168.0, 179.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 69.0, 67.0, 65.0, 65.0, 68.0, 69.0, 63.0, 65.0], "value": 66.8, "unit": "score"}]}]} 16:16:25 INFO - Return code: 0 16:16:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:16:25 INFO - # TBPL SUCCESS # 16:16:25 INFO - Running post-action listener: _package_coverage_data 16:16:25 INFO - Running post-action listener: _resource_record_post_action 16:16:25 INFO - [mozharness: 2017-09-22 23:16:25.674293Z] Finished run-tests step (success) 16:16:25 INFO - Running post-run listener: _resource_record_post_run 16:16:25 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 12288; Write bytes: 403152896; Read time: 64; Write time: 249136 16:16:25 INFO - TinderboxPrint: CPU usage
13.1% 16:16:25 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 64 16:16:25 INFO - TinderboxPrint: I/O write bytes / time
403,152,896 / 249,136 16:16:25 INFO - TinderboxPrint: CPU idle
8,233.3 (86.9%) 16:16:25 INFO - TinderboxPrint: CPU user
1,194.3 (12.6%) 16:16:25 INFO - TinderboxPrint: Swap in / out
0 / 0 16:16:25 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 74653696; Read time: 0; Write time: 38468 16:16:25 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:16:25 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 8192; Write bytes: 328499200; Read time: 48; Write time: 210668 16:16:26 INFO - Running post-run listener: _upload_blobber_files 16:16:26 INFO - Blob upload gear active. 16:16:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:16:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:16:26 INFO - (blobuploader) - INFO - Open directory for files ... 16:16:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:16:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:16:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:28 INFO - (blobuploader) - INFO - Done attempting. 16:16:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:16:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:16:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:29 INFO - (blobuploader) - INFO - Done attempting. 16:16:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:16:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:16:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:36 INFO - (blobuploader) - INFO - Done attempting. 16:16:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:16:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:16:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:37 INFO - (blobuploader) - INFO - Done attempting. 16:16:37 INFO - (blobuploader) - INFO - Iteration through files over. 16:16:37 INFO - Return code: 0 16:16:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:16:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:16:37 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6ce507a19a3724cffb90236e8fcca729c4eec64890c5374346270be693d82f667ab0a08f9117053f59d729edf4a63ee20b093d2d5b5f349d32a88a530e95abc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59ab6d8e479532e26d35f6431c653aa6a037c30e45c58402148cceef3cb3085d9cd7eac8fbb1450b0f4e13af48bff638ce3eee7ba23bcfb3a4d3344843968282", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a8e94db7138a0f23331752e79840f3f8e601d0e8b109ca25ad86e6688ff36b106f76c1b82bbb7987860854f4a2a0b82188f9a6782aaf038625cb9114d6e542f0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39156e8831a95223c3da0391399835ecebc7709fd16a17b6e89400e9bd859ece36bfac9b825a6be73e19fcce4150aed2a32ea646707fcbe8f42f19269d1ed2e8"} 16:16:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:16:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:16:37 INFO - Contents: 16:16:37 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6ce507a19a3724cffb90236e8fcca729c4eec64890c5374346270be693d82f667ab0a08f9117053f59d729edf4a63ee20b093d2d5b5f349d32a88a530e95abc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59ab6d8e479532e26d35f6431c653aa6a037c30e45c58402148cceef3cb3085d9cd7eac8fbb1450b0f4e13af48bff638ce3eee7ba23bcfb3a4d3344843968282", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a8e94db7138a0f23331752e79840f3f8e601d0e8b109ca25ad86e6688ff36b106f76c1b82bbb7987860854f4a2a0b82188f9a6782aaf038625cb9114d6e542f0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39156e8831a95223c3da0391399835ecebc7709fd16a17b6e89400e9bd859ece36bfac9b825a6be73e19fcce4150aed2a32ea646707fcbe8f42f19269d1ed2e8"} 16:16:37 INFO - Running post-run listener: copy_logs_to_upload_dir 16:16:37 INFO - Copying logs to upload dir... 16:16:37 INFO - mkdir: /builds/slave/test/build/upload/logs 16:16:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.040497 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2017-09-22 16:16:37.365495) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 16:16:37.368820) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6ce507a19a3724cffb90236e8fcca729c4eec64890c5374346270be693d82f667ab0a08f9117053f59d729edf4a63ee20b093d2d5b5f349d32a88a530e95abc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59ab6d8e479532e26d35f6431c653aa6a037c30e45c58402148cceef3cb3085d9cd7eac8fbb1450b0f4e13af48bff638ce3eee7ba23bcfb3a4d3344843968282", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a8e94db7138a0f23331752e79840f3f8e601d0e8b109ca25ad86e6688ff36b106f76c1b82bbb7987860854f4a2a0b82188f9a6782aaf038625cb9114d6e542f0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39156e8831a95223c3da0391399835ecebc7709fd16a17b6e89400e9bd859ece36bfac9b825a6be73e19fcce4150aed2a32ea646707fcbe8f42f19269d1ed2e8"} build_url:https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011414 build_url: 'https://queue.taskcluster.net/v1/task/Rya6KjrkSJOt9EaGINvG8Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b6ce507a19a3724cffb90236e8fcca729c4eec64890c5374346270be693d82f667ab0a08f9117053f59d729edf4a63ee20b093d2d5b5f349d32a88a530e95abc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/59ab6d8e479532e26d35f6431c653aa6a037c30e45c58402148cceef3cb3085d9cd7eac8fbb1450b0f4e13af48bff638ce3eee7ba23bcfb3a4d3344843968282", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a8e94db7138a0f23331752e79840f3f8e601d0e8b109ca25ad86e6688ff36b106f76c1b82bbb7987860854f4a2a0b82188f9a6782aaf038625cb9114d6e542f0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/39156e8831a95223c3da0391399835ecebc7709fd16a17b6e89400e9bd859ece36bfac9b825a6be73e19fcce4150aed2a32ea646707fcbe8f42f19269d1ed2e8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 16:16:37.397428) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:16:37.397808) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1506120628.920485-1288158012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004669 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 16:16:37.482645) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 16:16:37.482950) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 16:16:37.483270) ========= ========= Total master_lag: 0.21 =========