builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-086 starttime: 1505381485.26 results: success (0) revision: 6266decf217d4da5a18115cb5b1ff70feb76e7a5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:25.259004) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:25.259368) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:25.259743) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:25.370121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:25.370416) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-14 02:31:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 218M=0s 2017-09-14 02:31:26 (218 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101537 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:25.507655) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:25.507989) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018435 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:25.602025) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:25.602348) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6266decf217d4da5a18115cb5b1ff70feb76e7a5 --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 6266decf217d4da5a18115cb5b1ff70feb76e7a5 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-14 02:31:26,470 truncating revision to first 12 chars 2017-09-14 02:31:26,470 Setting DEBUG logging. 2017-09-14 02:31:26,470 attempt 1/10 2017-09-14 02:31:26,470 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6266decf217d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-14 02:31:26,910 unpacking tar archive at: mozilla-central-6266decf217d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.772494 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:26.430878) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:26.431180) ========= script_repo_revision: 6266decf217d4da5a18115cb5b1ff70feb76e7a5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:26.431614) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:26.431883) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-14 02:31:26.576314) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-14 02:31:26.576698) ========= /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=438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:31:27 INFO - MultiFileLogger online at 20170914 02:31:27 in /builds/slave/test 02:31:27 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 02:31:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:31:27 INFO - {'append_to_log': False, 02:31:27 INFO - 'base_work_dir': '/builds/slave/test', 02:31:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:31:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:31:27 INFO - 'branch': 'Firefox-Non-PGO', 02:31:27 INFO - 'buildbot_json_path': 'buildprops.json', 02:31:27 INFO - 'code_coverage': False, 02:31:27 INFO - 'config_files': ('talos/linux_config.py',), 02:31:27 INFO - 'default_actions': ('clobber', 02:31:27 INFO - 'read-buildbot-config', 02:31:27 INFO - 'download-and-extract', 02:31:27 INFO - 'populate-webroot', 02:31:27 INFO - 'create-virtualenv', 02:31:27 INFO - 'install', 02:31:27 INFO - 'setup-mitmproxy', 02:31:27 INFO - 'run-tests'), 02:31:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:31:27 INFO - 'disable_ccov_upload': False, 02:31:27 INFO - 'disable_stylo': False, 02:31:27 INFO - 'download_minidump_stackwalk': True, 02:31:27 INFO - 'download_symbols': 'ondemand', 02:31:27 INFO - 'enable_stylo': False, 02:31:27 INFO - 'enable_webrender': False, 02:31:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:31:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:31:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:31:27 INFO - 'gecko_profile': False, 02:31:27 INFO - 'gecko_profile_interval': 0, 02:31:27 INFO - 'installer_path': 'installer.exe', 02:31:27 INFO - 'log_level': 'info', 02:31:27 INFO - 'log_name': 'talos', 02:31:27 INFO - 'log_to_console': True, 02:31:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:31:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:31:27 INFO - 'opt_config_files': (), 02:31:27 INFO - 'pip_index': False, 02:31:27 INFO - 'suite': 'dromaeojs-e10s', 02:31:27 INFO - 'system_bits': '32', 02:31:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:31:27 INFO - 'title': 'talos-linux64-ix-086', 02:31:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:31:27 INFO - 'use_talos_json': True, 02:31:27 INFO - 'verify': 'False', 02:31:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:31:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:31:27 INFO - 'work_dir': 'build'} 02:31:27 INFO - [mozharness: 2017-09-14 09:31:27.583674Z] Running clobber step. 02:31:27 INFO - Running pre-action listener: _resource_record_pre_action 02:31:27 INFO - Running main action method: clobber 02:31:27 INFO - rmtree: /builds/slave/test/build 02:31:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:31:29 INFO - Running post-action listener: _resource_record_post_action 02:31:29 INFO - [mozharness: 2017-09-14 09:31:29.087460Z] Finished clobber step (success) 02:31:29 INFO - [mozharness: 2017-09-14 09:31:29.087558Z] Running read-buildbot-config step. 02:31:29 INFO - Running pre-action listener: _resource_record_pre_action 02:31:29 INFO - Running main action method: read_buildbot_config 02:31:29 INFO - Using buildbot properties: 02:31:29 INFO - { 02:31:29 INFO - "project": "", 02:31:29 INFO - "product": "firefox", 02:31:29 INFO - "who": "archaeopteryx@coole-files.de", 02:31:29 INFO - "installer_path": "public/build/target.tar.bz2", 02:31:29 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:31:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:31:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 02:31:29 INFO - "stage_platform": "linux64", 02:31:29 INFO - "basedir": "/builds/slave/test", 02:31:29 INFO - "buildnumber": 823, 02:31:29 INFO - "platform": "ubuntu64_hw", 02:31:29 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:31:29 INFO - "slavebuilddir": "test", 02:31:29 INFO - "taskId": "MbnPHZVgSYCY8iDLwKEe3Q", 02:31:29 INFO - "branch": "mozilla-central", 02:31:29 INFO - "script_repo_revision": "production", 02:31:29 INFO - "revision": "6266decf217d4da5a18115cb5b1ff70feb76e7a5", 02:31:29 INFO - "slavename": "talos-linux64-ix-086", 02:31:29 INFO - "repo_path": "mozilla-central" 02:31:29 INFO - } 02:31:29 INFO - Finding installer, test and symbols from parent task. 02:31:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MbnPHZVgSYCY8iDLwKEe3Q'}, attempt #1 02:31:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MbnPHZVgSYCY8iDLwKEe3Q'}, attempt #1 02:31:30 INFO - Task dependencies: dyqypZ5BQ3uJrBBpjoZhjw 02:31:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw'}, attempt #1 02:31:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.tar.bz2 02:31:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.test_packages.json 02:31:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.crashreporter-symbols.zip 02:31:30 INFO - Running post-action listener: _resource_record_post_action 02:31:30 INFO - [mozharness: 2017-09-14 09:31:30.538774Z] Finished read-buildbot-config step (success) 02:31:30 INFO - [mozharness: 2017-09-14 09:31:30.538994Z] Running download-and-extract step. 02:31:30 INFO - Running pre-action listener: _resource_record_pre_action 02:31:30 INFO - Running main action method: download_and_extract 02:31:30 INFO - mkdir: /builds/slave/test/build/tests 02:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:30 INFO - trying https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.test_packages.json 02:31:30 INFO - Downloading https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:31:31 INFO - Downloaded 989 bytes. 02:31:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:31:31 INFO - Using the following test package requirements: 02:31:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:31:31 INFO - u'common': [u'target.common.tests.zip'], 02:31:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:31:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:31:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:31:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:31:31 INFO - u'mozbase': [u'target.common.tests.zip'], 02:31:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:31:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:31:31 INFO - u'web-platform': [u'target.common.tests.zip', 02:31:31 INFO - u'target.web-platform.tests.tar.gz'], 02:31:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:31:31 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 02:31:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.common.tests.zip 02:31:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:31:31 INFO - Fetch https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.common.tests.zip into memory 02:31:32 INFO - Content-Length response header: 54588745 02:31:32 INFO - Bytes received: 54588745 02:31:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.talos.tests.zip 02:31:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:31:43 INFO - Fetch https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.talos.tests.zip into memory 02:31:44 INFO - Content-Length response header: 14500356 02:31:44 INFO - Bytes received: 14500356 02:31:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:44 INFO - trying https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.tar.bz2 02:31:44 INFO - Downloading https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:31:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:31:47 INFO - Downloaded 61444046 bytes. 02:31:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.tar.bz2 02:31:47 INFO - mkdir: /builds/slave/test/properties 02:31:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:31:47 INFO - Writing to file /builds/slave/test/properties/build_url 02:31:47 INFO - Contents: 02:31:47 INFO - build_url:https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.tar.bz2 02:31:47 INFO - Running post-action listener: _resource_record_post_action 02:31:47 INFO - Running post-action listener: find_tests_for_verification 02:31:47 INFO - Running post-action listener: set_extra_try_arguments 02:31:47 INFO - [mozharness: 2017-09-14 09:31:47.640081Z] Finished download-and-extract step (success) 02:31:47 INFO - [mozharness: 2017-09-14 09:31:47.640179Z] Running populate-webroot step. 02:31:47 INFO - Running pre-action listener: _resource_record_pre_action 02:31:47 INFO - Running main action method: populate_webroot 02:31:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:31:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['tresize', 02:31:47 INFO - 'tcanvasmark']}, 02:31:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:31:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['dromaeo_css', 02:31:47 INFO - 'kraken']}, 02:31:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:31:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:31:47 INFO - 'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:31:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:31:47 INFO - 'tests': ['damp', 'tps']}, 02:31:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:31:47 INFO - 'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['damp', 'tps']}, 02:31:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:31:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['dromaeo_dom']}, 02:31:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:31:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['basic_compositor_video', 02:31:47 INFO - 'glvideo']}, 02:31:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:31:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:31:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:31:47 INFO - 'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['ts_paint_webext', 02:31:47 INFO - 'tp5o_webext']}, 02:31:47 INFO - 'other-e10s': {'tests': ['a11yr', 02:31:47 INFO - 'ts_paint', 02:31:47 INFO - 'tpaint', 02:31:47 INFO - 'sessionrestore', 02:31:47 INFO - 'sessionrestore_many_windows', 02:31:47 INFO - 'sessionrestore_no_auto_restore', 02:31:47 INFO - 'tabpaint']}, 02:31:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['a11yr', 02:31:47 INFO - 'ts_paint', 02:31:47 INFO - 'tpaint', 02:31:47 INFO - 'sessionrestore', 02:31:47 INFO - 'sessionrestore_many_windows', 02:31:47 INFO - 'sessionrestore_no_auto_restore', 02:31:47 INFO - 'tabpaint']}, 02:31:47 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:31:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:31:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['bloom_basic_singleton']}, 02:31:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['bloom_basic']}, 02:31:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:31:47 INFO - 'tsvgr_opacity', 02:31:47 INFO - 'tart', 02:31:47 INFO - 'tscrollx', 02:31:47 INFO - 'tsvg_static']}, 02:31:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['tsvgx', 02:31:47 INFO - 'tsvgr_opacity', 02:31:47 INFO - 'tart', 02:31:47 INFO - 'tscrollx', 02:31:47 INFO - 'tsvg_static']}, 02:31:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:31:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:31:47 INFO - 'talos_options': ['--disable-stylo'], 02:31:47 INFO - 'tests': ['tp5o']}, 02:31:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:31:47 INFO - 'talos_options': ['--mitmproxy', 02:31:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:31:47 INFO - '--firstNonBlankPaint'], 02:31:47 INFO - 'tests': ['tp6_google', 02:31:47 INFO - 'tp6_youtube', 02:31:47 INFO - 'tp6_amazon', 02:31:47 INFO - 'tp6_facebook']}, 02:31:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:31:47 INFO - 'talos_options': ['--disable-stylo', 02:31:47 INFO - '--mitmproxy', 02:31:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:31:47 INFO - '--firstNonBlankPaint'], 02:31:47 INFO - 'tests': ['tp6_google', 02:31:47 INFO - 'tp6_youtube', 02:31:47 INFO - 'tp6_amazon', 02:31:47 INFO - 'tp6_facebook']}, 02:31:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:31:47 INFO - 'talos_options': ['--stylo-threads=1', 02:31:47 INFO - '--mitmproxy', 02:31:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:31:47 INFO - '--firstNonBlankPaint'], 02:31:47 INFO - 'tests': ['tp6_google', 02:31:47 INFO - 'tp6_youtube', 02:31:47 INFO - 'tp6_amazon', 02:31:47 INFO - 'tp6_facebook']}, 02:31:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:31:47 INFO - 'talos_options': ['--xperf_path', 02:31:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:47 INFO - 'tests': ['tp5n']}, 02:31:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:31:47 INFO - 'talos_options': ['--disable-stylo', 02:31:47 INFO - '--xperf_path', 02:31:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:31:47 INFO - 'tests': ['tp5n']}}} 02:31:47 INFO - Running post-action listener: _resource_record_post_action 02:31:47 INFO - [mozharness: 2017-09-14 09:31:47.655226Z] Finished populate-webroot step (success) 02:31:47 INFO - [mozharness: 2017-09-14 09:31:47.655315Z] Running create-virtualenv step. 02:31:47 INFO - Running pre-action listener: _resource_record_pre_action 02:31:47 INFO - Running main action method: create_virtualenv 02:31:47 INFO - Creating virtualenv /builds/slave/test/build/venv 02:31:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:31:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:31:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:31:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:31:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:31:48 INFO - Installing distribute.............................................................................................................................................................................................done. 02:31:48 INFO - Installing pip...............done. 02:31:48 INFO - Return code: 0 02:31:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:31:48 INFO - Reading from file tmpfile_stdout 02:31:48 INFO - Output received: 02:31:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:31:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:31:48 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')]} 02:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:48 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')]} 02:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:48 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 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:31:48 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 02:31:48 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 02:31:48 INFO - Using env: {'DISPLAY': ':0', 02:31:48 INFO - 'HOME': '/home/cltbld', 02:31:48 INFO - 'LANG': 'en_US.UTF-8', 02:31:48 INFO - 'LANGUAGE': 'en_US:en', 02:31:48 INFO - 'LOGNAME': 'cltbld', 02:31:48 INFO - 'MAIL': '/var/mail/cltbld', 02:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:48 INFO - 'MOZ_NO_REMOTE': '1', 02:31:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:48 INFO - 'NO_EM_RESTART': '1', 02:31:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:48 INFO - 'PWD': '/builds/slave/test', 02:31:48 INFO - 'SHELL': '/bin/bash', 02:31:48 INFO - 'SHLVL': '1', 02:31:48 INFO - 'TERM': 'linux', 02:31:48 INFO - 'TMOUT': '86400', 02:31:48 INFO - 'USER': 'cltbld', 02:31:48 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:48 INFO - '_': '/tools/buildbot/bin/python'} 02:31:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:31:49 INFO - Downloading/unpacking pip>=1.5 02:31:49 INFO - Running setup.py egg_info for package pip 02:31:49 INFO - warning: no files found matching 'pip/cacert.pem' 02:31:49 INFO - warning: no files found matching '*.html' under directory 'docs' 02:31:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:31:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:31:49 INFO - Installing collected packages: pip 02:31:49 INFO - Found existing installation: pip 1.1 02:31:49 INFO - Uninstalling pip: 02:31:49 INFO - Successfully uninstalled pip 02:31:49 INFO - Running setup.py install for pip 02:31:49 INFO - warning: no files found matching 'pip/cacert.pem' 02:31:49 INFO - warning: no files found matching '*.html' under directory 'docs' 02:31:49 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:31:49 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:31:49 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:31:49 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:31:49 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:31:49 INFO - Successfully installed pip 02:31:49 INFO - Cleaning up... 02:31:49 INFO - Return code: 0 02:31:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:31:49 INFO - Using env: {'DISPLAY': ':0', 02:31:49 INFO - 'HOME': '/home/cltbld', 02:31:49 INFO - 'LANG': 'en_US.UTF-8', 02:31:49 INFO - 'LANGUAGE': 'en_US:en', 02:31:49 INFO - 'LOGNAME': 'cltbld', 02:31:49 INFO - 'MAIL': '/var/mail/cltbld', 02:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:49 INFO - 'MOZ_NO_REMOTE': '1', 02:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:49 INFO - 'NO_EM_RESTART': '1', 02:31:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:49 INFO - 'PWD': '/builds/slave/test', 02:31:49 INFO - 'SHELL': '/bin/bash', 02:31:49 INFO - 'SHLVL': '1', 02:31:49 INFO - 'TERM': 'linux', 02:31:49 INFO - 'TMOUT': '86400', 02:31:49 INFO - 'USER': 'cltbld', 02:31:49 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:49 INFO - '_': '/tools/buildbot/bin/python'} 02:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:50 INFO - Downloading/unpacking psutil>=3.1.1 02:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:31:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:51 INFO - Installing collected packages: psutil 02:31:51 INFO - Running setup.py install for psutil 02:31:51 INFO - building 'psutil._psutil_linux' extension 02:31:51 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 02:31:51 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 02:31:51 INFO - building 'psutil._psutil_posix' extension 02:31:51 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 02:31:51 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 02:31:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:31:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:31:51 INFO - Successfully installed psutil 02:31:51 INFO - Cleaning up... 02:31:52 INFO - Return code: 0 02:31:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:31:52 INFO - Using env: {'DISPLAY': ':0', 02:31:52 INFO - 'HOME': '/home/cltbld', 02:31:52 INFO - 'LANG': 'en_US.UTF-8', 02:31:52 INFO - 'LANGUAGE': 'en_US:en', 02:31:52 INFO - 'LOGNAME': 'cltbld', 02:31:52 INFO - 'MAIL': '/var/mail/cltbld', 02:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:52 INFO - 'MOZ_NO_REMOTE': '1', 02:31:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:52 INFO - 'NO_EM_RESTART': '1', 02:31:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:52 INFO - 'PWD': '/builds/slave/test', 02:31:52 INFO - 'SHELL': '/bin/bash', 02:31:52 INFO - 'SHLVL': '1', 02:31:52 INFO - 'TERM': 'linux', 02:31:52 INFO - 'TMOUT': '86400', 02:31:52 INFO - 'USER': 'cltbld', 02:31:52 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:52 INFO - '_': '/tools/buildbot/bin/python'} 02:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:31:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:31:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:31:53 INFO - Installing collected packages: mozsystemmonitor 02:31:53 INFO - Running setup.py install for mozsystemmonitor 02:31:53 INFO - Successfully installed mozsystemmonitor 02:31:53 INFO - Cleaning up... 02:31:54 INFO - Return code: 0 02:31:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:31:54 INFO - Using env: {'DISPLAY': ':0', 02:31:54 INFO - 'HOME': '/home/cltbld', 02:31:54 INFO - 'LANG': 'en_US.UTF-8', 02:31:54 INFO - 'LANGUAGE': 'en_US:en', 02:31:54 INFO - 'LOGNAME': 'cltbld', 02:31:54 INFO - 'MAIL': '/var/mail/cltbld', 02:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:54 INFO - 'MOZ_NO_REMOTE': '1', 02:31:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:54 INFO - 'NO_EM_RESTART': '1', 02:31:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:54 INFO - 'PWD': '/builds/slave/test', 02:31:54 INFO - 'SHELL': '/bin/bash', 02:31:54 INFO - 'SHLVL': '1', 02:31:54 INFO - 'TERM': 'linux', 02:31:54 INFO - 'TMOUT': '86400', 02:31:54 INFO - 'USER': 'cltbld', 02:31:54 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:54 INFO - '_': '/tools/buildbot/bin/python'} 02:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:54 INFO - Downloading/unpacking jsonschema==2.5.1 02:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:31:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:31:55 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 02:31:55 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 02:31:55 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 02:31:55 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 02:31:55 INFO - Downloading functools32-3.2.3-2.tar.gz 02:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:31:56 INFO - warning: no files found matching '*.txt' 02:31:56 INFO - no previously-included directories found matching 'build' 02:31:56 INFO - no previously-included directories found matching 'dist' 02:31:56 INFO - no previously-included directories found matching '.git*' 02:31:56 INFO - Installing collected packages: jsonschema, functools32 02:31:56 INFO - Running setup.py install for functools32 02:31:56 INFO - warning: no files found matching '*.txt' 02:31:56 INFO - no previously-included directories found matching 'build' 02:31:56 INFO - no previously-included directories found matching 'dist' 02:31:56 INFO - no previously-included directories found matching '.git*' 02:31:56 INFO - Successfully installed jsonschema functools32 02:31:56 INFO - Cleaning up... 02:31:56 INFO - Return code: 0 02:31:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:31:56 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')]} 02:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:56 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')]} 02:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:56 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 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:31:56 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 02:31:56 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 02:31:56 INFO - Using env: {'DISPLAY': ':0', 02:31:56 INFO - 'HOME': '/home/cltbld', 02:31:56 INFO - 'LANG': 'en_US.UTF-8', 02:31:56 INFO - 'LANGUAGE': 'en_US:en', 02:31:56 INFO - 'LOGNAME': 'cltbld', 02:31:56 INFO - 'MAIL': '/var/mail/cltbld', 02:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:56 INFO - 'MOZ_NO_REMOTE': '1', 02:31:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:56 INFO - 'NO_EM_RESTART': '1', 02:31:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:56 INFO - 'PWD': '/builds/slave/test', 02:31:56 INFO - 'SHELL': '/bin/bash', 02:31:56 INFO - 'SHLVL': '1', 02:31:56 INFO - 'TERM': 'linux', 02:31:56 INFO - 'TMOUT': '86400', 02:31:56 INFO - 'USER': 'cltbld', 02:31:56 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:56 INFO - '_': '/tools/buildbot/bin/python'} 02:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:31:56 INFO - Cleaning up... 02:31:56 INFO - Return code: 0 02:31:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:31:56 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')]} 02:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:56 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')]} 02:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:56 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 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:31:56 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 02:31:56 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 02:31:56 INFO - Using env: {'DISPLAY': ':0', 02:31:56 INFO - 'HOME': '/home/cltbld', 02:31:56 INFO - 'LANG': 'en_US.UTF-8', 02:31:56 INFO - 'LANGUAGE': 'en_US:en', 02:31:56 INFO - 'LOGNAME': 'cltbld', 02:31:56 INFO - 'MAIL': '/var/mail/cltbld', 02:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:56 INFO - 'MOZ_NO_REMOTE': '1', 02:31:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:56 INFO - 'NO_EM_RESTART': '1', 02:31:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:56 INFO - 'PWD': '/builds/slave/test', 02:31:56 INFO - 'SHELL': '/bin/bash', 02:31:56 INFO - 'SHLVL': '1', 02:31:56 INFO - 'TERM': 'linux', 02:31:56 INFO - 'TMOUT': '86400', 02:31:56 INFO - 'USER': 'cltbld', 02:31:56 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:56 INFO - '_': '/tools/buildbot/bin/python'} 02:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:56 INFO - Downloading/unpacking blobuploader==1.2.4 02:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:57 INFO - Downloading blobuploader-1.2.4.tar.gz 02:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:31:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:31:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:31:58 INFO - Downloading docopt-0.6.1.tar.gz 02:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:31:58 INFO - Installing collected packages: blobuploader, requests, docopt 02:31:58 INFO - Running setup.py install for blobuploader 02:31:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:31:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:31:58 INFO - Running setup.py install for requests 02:31:58 INFO - Running setup.py install for docopt 02:31:59 INFO - Successfully installed blobuploader requests docopt 02:31:59 INFO - Cleaning up... 02:31:59 INFO - Return code: 0 02:31:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:31:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:31:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:31:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:31:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:31:59 INFO - Using env: {'DISPLAY': ':0', 02:31:59 INFO - 'HOME': '/home/cltbld', 02:31:59 INFO - 'LANG': 'en_US.UTF-8', 02:31:59 INFO - 'LANGUAGE': 'en_US:en', 02:31:59 INFO - 'LOGNAME': 'cltbld', 02:31:59 INFO - 'MAIL': '/var/mail/cltbld', 02:31:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:59 INFO - 'MOZ_NO_REMOTE': '1', 02:31:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:59 INFO - 'NO_EM_RESTART': '1', 02:31:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:59 INFO - 'PWD': '/builds/slave/test', 02:31:59 INFO - 'SHELL': '/bin/bash', 02:31:59 INFO - 'SHLVL': '1', 02:31:59 INFO - 'TERM': 'linux', 02:31:59 INFO - 'TMOUT': '86400', 02:31:59 INFO - 'USER': 'cltbld', 02:31:59 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:31:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:59 INFO - '_': '/tools/buildbot/bin/python'} 02:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:31:59 INFO - Running setup.py (path:/tmp/pip-wQLRie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:31:59 INFO - Running setup.py (path:/tmp/pip-BseIvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:31:59 INFO - Running setup.py (path:/tmp/pip-J0RXkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:31:59 INFO - Running setup.py (path:/tmp/pip-UHsPtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:31:59 INFO - Running setup.py (path:/tmp/pip-uncnwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:31:59 INFO - Running setup.py (path:/tmp/pip-icYvv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:31:59 INFO - Running setup.py (path:/tmp/pip-jsE6eZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:31:59 INFO - Running setup.py (path:/tmp/pip-MRfkCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:31:59 INFO - Running setup.py (path:/tmp/pip-yy35SB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:31:59 INFO - Running setup.py (path:/tmp/pip-dj8qcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:31:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:31:59 INFO - Running setup.py (path:/tmp/pip-NNo4Ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:00 INFO - Running setup.py (path:/tmp/pip-qD6Qap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:00 INFO - Running setup.py (path:/tmp/pip-yG7Ysd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:00 INFO - Running setup.py (path:/tmp/pip-2Sb8SR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:00 INFO - Running setup.py (path:/tmp/pip-lOIlOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:00 INFO - Running setup.py (path:/tmp/pip-zINrKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:00 INFO - Running setup.py (path:/tmp/pip-5WSQ8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:32:00 INFO - Running setup.py install for manifestparser 02:32:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:32:00 INFO - Running setup.py install for mozcrash 02:32:00 INFO - Running setup.py install for mozdebug 02:32:00 INFO - Running setup.py install for mozdevice 02:32:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:32:00 INFO - Running setup.py install for mozfile 02:32:01 INFO - Running setup.py install for mozhttpd 02:32:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Running setup.py install for mozinfo 02:32:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Running setup.py install for mozInstall 02:32:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Running setup.py install for mozleak 02:32:01 INFO - Running setup.py install for mozlog 02:32:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Running setup.py install for moznetwork 02:32:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Running setup.py install for mozprocess 02:32:01 INFO - Running setup.py install for mozprofile 02:32:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Running setup.py install for mozrunner 02:32:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:32:01 INFO - Running setup.py install for mozscreenshot 02:32:02 INFO - Running setup.py install for moztest 02:32:02 INFO - Running setup.py install for mozversion 02:32:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:32:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:32:02 INFO - Cleaning up... 02:32:02 INFO - Return code: 0 02:32:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:02 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')]} 02:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:02 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')]} 02:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:02 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 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:32:02 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 02:32:02 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 02:32:02 INFO - Using env: {'DISPLAY': ':0', 02:32:02 INFO - 'HOME': '/home/cltbld', 02:32:02 INFO - 'LANG': 'en_US.UTF-8', 02:32:02 INFO - 'LANGUAGE': 'en_US:en', 02:32:02 INFO - 'LOGNAME': 'cltbld', 02:32:02 INFO - 'MAIL': '/var/mail/cltbld', 02:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:02 INFO - 'MOZ_NO_REMOTE': '1', 02:32:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:02 INFO - 'NO_EM_RESTART': '1', 02:32:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:02 INFO - 'PWD': '/builds/slave/test', 02:32:02 INFO - 'SHELL': '/bin/bash', 02:32:02 INFO - 'SHLVL': '1', 02:32:02 INFO - 'TERM': 'linux', 02:32:02 INFO - 'TMOUT': '86400', 02:32:02 INFO - 'USER': 'cltbld', 02:32:02 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:02 INFO - '_': '/tools/buildbot/bin/python'} 02:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:02 INFO - Running setup.py (path:/tmp/pip-8R9rOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:02 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)) 02:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:02 INFO - Running setup.py (path:/tmp/pip-hBQJtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:02 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)) 02:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:02 INFO - Running setup.py (path:/tmp/pip-_6bfOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:02 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)) 02:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:02 INFO - Running setup.py (path:/tmp/pip-B5ViW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:02 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)) 02:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:02 INFO - Running setup.py (path:/tmp/pip-oZzaNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:02 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)) 02:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:02 INFO - Running setup.py (path:/tmp/pip-w7zs5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:02 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)) 02:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:02 INFO - Running setup.py (path:/tmp/pip-8AusXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:03 INFO - Running setup.py (path:/tmp/pip-MJx8Yi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:03 INFO - Running setup.py (path:/tmp/pip-FnK22E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:03 INFO - Running setup.py (path:/tmp/pip-cP3bAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:03 INFO - Running setup.py (path:/tmp/pip-W_XkyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:03 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)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:03 INFO - Running setup.py (path:/tmp/pip-403Rkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:03 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)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:03 INFO - Running setup.py (path:/tmp/pip-WG9mlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:03 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)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:03 INFO - Running setup.py (path:/tmp/pip-7NRBXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:03 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)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:03 INFO - Running setup.py (path:/tmp/pip-1rvuWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:03 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)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:03 INFO - Running setup.py (path:/tmp/pip-vQpp1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:03 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)) 02:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:03 INFO - Running setup.py (path:/tmp/pip-7_oy11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:03 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)) 02:32:03 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)) 02:32:03 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)) 02:32:03 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)) 02:32:03 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)) 02:32:03 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)) 02:32:03 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)) 02:32:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32:03 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 02:32:03 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 02:32:03 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 02:32:03 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 02:32:04 INFO - Downloading blessings-1.6.tar.gz 02:32:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:32:04 INFO - Installing collected packages: blessings 02:32:04 INFO - Running setup.py install for blessings 02:32:05 INFO - Successfully installed blessings 02:32:05 INFO - Cleaning up... 02:32:05 INFO - Return code: 0 02:32:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:32:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:05 INFO - Reading from file tmpfile_stdout 02:32:05 INFO - Current package versions: 02:32:05 INFO - argparse == 1.2.1 02:32:05 INFO - blessings == 1.6 02:32:05 INFO - blobuploader == 1.2.4 02:32:05 INFO - docopt == 0.6.1 02:32:05 INFO - functools32 == 3.2.3-2 02:32:05 INFO - jsonschema == 2.5.1 02:32:05 INFO - manifestparser == 1.1 02:32:05 INFO - mozInstall == 1.13 02:32:05 INFO - mozcrash == 1.0 02:32:05 INFO - mozdebug == 0.1 02:32:05 INFO - mozdevice == 0.51 02:32:05 INFO - mozfile == 1.2 02:32:05 INFO - mozhttpd == 0.7 02:32:05 INFO - mozinfo == 0.10 02:32:05 INFO - mozleak == 0.1 02:32:05 INFO - mozlog == 3.5 02:32:05 INFO - moznetwork == 0.27 02:32:05 INFO - mozprocess == 0.25 02:32:05 INFO - mozprofile == 0.28 02:32:05 INFO - mozrunner == 6.13 02:32:05 INFO - mozscreenshot == 0.1 02:32:05 INFO - mozsystemmonitor == 0.3 02:32:05 INFO - moztest == 0.8 02:32:05 INFO - mozversion == 1.4 02:32:05 INFO - psutil == 3.1.1 02:32:05 INFO - requests == 1.2.3 02:32:05 INFO - wsgiref == 0.1.2 02:32:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:05 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')]} 02:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:05 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')]} 02:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:05 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 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:32:05 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 02:32:05 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 02:32:05 INFO - Using env: {'DISPLAY': ':0', 02:32:05 INFO - 'HOME': '/home/cltbld', 02:32:05 INFO - 'LANG': 'en_US.UTF-8', 02:32:05 INFO - 'LANGUAGE': 'en_US:en', 02:32:05 INFO - 'LOGNAME': 'cltbld', 02:32:05 INFO - 'MAIL': '/var/mail/cltbld', 02:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:05 INFO - 'MOZ_NO_REMOTE': '1', 02:32:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:05 INFO - 'NO_EM_RESTART': '1', 02:32:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:05 INFO - 'PWD': '/builds/slave/test', 02:32:05 INFO - 'SHELL': '/bin/bash', 02:32:05 INFO - 'SHLVL': '1', 02:32:05 INFO - 'TERM': 'linux', 02:32:05 INFO - 'TMOUT': '86400', 02:32:05 INFO - 'USER': 'cltbld', 02:32:05 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:05 INFO - '_': '/tools/buildbot/bin/python'} 02:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:05 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)) 02:32:05 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)) 02:32:05 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)) 02:32:05 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)) 02:32:05 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)) 02:32:05 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)) 02:32:05 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)) 02:32:05 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)) 02:32:05 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)) 02:32:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:32:05 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 02:32:05 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 02:32:05 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 02:32:05 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 02:32:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:32:06 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)) 02:32:06 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)) 02:32:06 INFO - Installing collected packages: simplejson 02:32:06 INFO - Running setup.py install for simplejson 02:32:06 INFO - building 'simplejson._speedups' extension 02:32:06 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 02:32:07 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 02:32:07 INFO - Successfully installed simplejson 02:32:07 INFO - Cleaning up... 02:32:07 INFO - Return code: 0 02:32:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:32:07 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')]} 02:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:07 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')]} 02:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:07 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 0x1d521f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20bea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', '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 02:32:07 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 02:32:07 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 02:32:07 INFO - Using env: {'DISPLAY': ':0', 02:32:07 INFO - 'HOME': '/home/cltbld', 02:32:07 INFO - 'LANG': 'en_US.UTF-8', 02:32:07 INFO - 'LANGUAGE': 'en_US:en', 02:32:07 INFO - 'LOGNAME': 'cltbld', 02:32:07 INFO - 'MAIL': '/var/mail/cltbld', 02:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:07 INFO - 'MOZ_NO_REMOTE': '1', 02:32:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:07 INFO - 'NO_EM_RESTART': '1', 02:32:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:07 INFO - 'PWD': '/builds/slave/test', 02:32:07 INFO - 'SHELL': '/bin/bash', 02:32:07 INFO - 'SHLVL': '1', 02:32:07 INFO - 'TERM': 'linux', 02:32:07 INFO - 'TMOUT': '86400', 02:32:07 INFO - 'USER': 'cltbld', 02:32:07 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:07 INFO - '_': '/tools/buildbot/bin/python'} 02:32:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:32:07 INFO - Cleaning up... 02:32:07 INFO - Return code: 0 02:32:07 INFO - Running post-action listener: _resource_record_post_action 02:32:07 INFO - Running post-action listener: _start_resource_monitoring 02:32:08 INFO - Starting resource monitoring. 02:32:08 INFO - [mozharness: 2017-09-14 09:32:08.003310Z] Finished create-virtualenv step (success) 02:32:08 INFO - [mozharness: 2017-09-14 09:32:08.003815Z] Running install step. 02:32:08 INFO - Running pre-action listener: _resource_record_pre_action 02:32:08 INFO - Running main action method: install 02:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:08 INFO - Reading from file tmpfile_stdout 02:32:08 INFO - Detecting whether we're running mozinstall >=1.0... 02:32:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:32:08 INFO - Reading from file tmpfile_stdout 02:32:08 INFO - Output received: 02:32:08 INFO - Usage: mozinstall [options] installer 02:32:08 INFO - Options: 02:32:08 INFO - -h, --help show this help message and exit 02:32:08 INFO - -d DEST, --destination=DEST 02:32:08 INFO - Directory to install application into. [default: 02:32:08 INFO - "/builds/slave/test"] 02:32:08 INFO - --app=APP Application being installed. [default: firefox] 02:32:08 INFO - mkdir: /builds/slave/test/build/application 02:32:08 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'] 02:32:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:32:27 INFO - Reading from file tmpfile_stdout 02:32:27 INFO - Output received: 02:32:27 INFO - /builds/slave/test/build/application/firefox/firefox 02:32:27 INFO - Running post-action listener: _resource_record_post_action 02:32:27 INFO - [mozharness: 2017-09-14 09:32:27.326259Z] Finished install step (success) 02:32:27 INFO - [mozharness: 2017-09-14 09:32:27.326515Z] Running setup-mitmproxy step. 02:32:27 INFO - Running pre-action listener: _resource_record_pre_action 02:32:27 INFO - Running main action method: setup_mitmproxy 02:32:27 INFO - Skipping: mitmproxy is not required 02:32:27 INFO - Running post-action listener: _resource_record_post_action 02:32:27 INFO - [mozharness: 2017-09-14 09:32:27.327640Z] Finished setup-mitmproxy step (success) 02:32:27 INFO - [mozharness: 2017-09-14 09:32:27.327859Z] Running run-tests step. 02:32:27 INFO - Running pre-action listener: _resource_record_pre_action 02:32:27 INFO - Running pre-action listener: _set_gcov_prefix 02:32:27 INFO - Running main action method: run_tests 02:32:27 WARNING - Try message not found. 02:32:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:32:27 INFO - Python 2.7.3 02:32:27 INFO - Return code: 0 02:32:27 INFO - grabbing minidump binary from tooltool 02:32:27 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')]} 02:32:27 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 0x20c2090>, '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 0x20c2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bcf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:32:27 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 02:32:27 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 02:32:27 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 02:32:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:32:27 INFO - Return code: 0 02:32:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:32:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:32:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:32:27 INFO - ENV: RUST_BACKTRACE is now full 02:32:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:32:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:32:27 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/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:32:27 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/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-086 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:32:27 INFO - Using env: {'DISPLAY': ':0', 02:32:27 INFO - 'HOME': '/home/cltbld', 02:32:27 INFO - 'LANG': 'en_US.UTF-8', 02:32:27 INFO - 'LANGUAGE': 'en_US:en', 02:32:27 INFO - 'LOGNAME': 'cltbld', 02:32:27 INFO - 'MAIL': '/var/mail/cltbld', 02:32:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:32:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:27 INFO - 'MOZ_NO_REMOTE': '1', 02:32:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:32:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:27 INFO - 'NO_EM_RESTART': '1', 02:32:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:27 INFO - 'PWD': '/builds/slave/test', 02:32:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:32:27 INFO - 'RUST_BACKTRACE': 'full', 02:32:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:32:27 INFO - 'SHELL': '/bin/bash', 02:32:27 INFO - 'SHLVL': '1', 02:32:27 INFO - 'TERM': 'linux', 02:32:27 INFO - 'TMOUT': '86400', 02:32:27 INFO - 'USER': 'cltbld', 02:32:27 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:27 INFO - '_': '/tools/buildbot/bin/python'} 02:32:27 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/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:32:27 INFO - mozversion application_buildid: 20170914084540 02:32:27 INFO - mozversion application_changeset: 6266decf217d4da5a18115cb5b1ff70feb76e7a5 02:32:27 INFO - mozversion application_display_name: Nightly 02:32:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:32:27 INFO - mozversion application_name: Firefox 02:32:27 INFO - mozversion application_remotingname: firefox 02:32:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:32:27 INFO - mozversion application_vendor: Mozilla 02:32:27 INFO - mozversion application_version: 57.0a1 02:32:27 INFO - mozversion platform_buildid: 20170914084540 02:32:27 INFO - mozversion platform_changeset: 6266decf217d4da5a18115cb5b1ff70feb76e7a5 02:32:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:32:27 INFO - mozversion platform_version: 57.0a1 02:32:27 INFO - using testdate: 1505381547 02:32:27 INFO - actual date: 1505381547 02:32:27 INFO - starting webserver on 'localhost:51978' 02:32:27 INFO - SUITE-START | Running 2 tests 02:32:27 INFO - TEST-START | dromaeo_css 02:32:27 INFO - Initialising browser for dromaeo_css test... 02:32:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51978/getInfo.html -profile /tmp/tmpej4qQd/profile 02:32:27 INFO - TEST-INFO | started process 12667 (/builds/slave/test/build/application/firefox/firefox http://localhost:51978/getInfo.html) 02:32:33 INFO - TEST-INFO | 12667: exit 0 02:32:33 INFO - Browser initialized. 02:32:33 INFO - Running cycle 1/1 for dromaeo_css test... 02:32:33 INFO - Using env: {'DISPLAY': ':0', 02:32:33 INFO - 'HOME': '/home/cltbld', 02:32:33 INFO - 'JSGC_DISABLE_POISONING': '1', 02:32:33 INFO - 'LANG': 'en_US.UTF-8', 02:32:33 INFO - 'LANGUAGE': 'en_US:en', 02:32:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:32:33 INFO - 'LOGNAME': 'cltbld', 02:32:33 INFO - 'MAIL': '/var/mail/cltbld', 02:32:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:32:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:32:33 INFO - 'MOZ_CRASHREPORTER': '1', 02:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:32:33 INFO - 'MOZ_NO_REMOTE': '1', 02:32:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:32:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:33 INFO - 'NO_EM_RESTART': '1', 02:32:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:33 INFO - 'PWD': '/builds/slave/test', 02:32:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:32:33 INFO - 'RUST_BACKTRACE': 'full', 02:32:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:32:33 INFO - 'SHELL': '/bin/bash', 02:32:33 INFO - 'SHLVL': '1', 02:32:33 INFO - 'TERM': 'linux', 02:32:33 INFO - 'TMOUT': '86400', 02:32:33 INFO - 'USER': 'cltbld', 02:32:33 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:33 INFO - '_': '/tools/buildbot/bin/python'} 02:32:33 INFO - TEST-INFO | started process 12899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpej4qQd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:32:34 INFO - PID 12899 | 02:32:34 INFO - PID 12899 | (/builds/slave/test/build/application/firefox/firefox:12948): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:32:34 INFO - PID 12899 | 02:32:44 INFO - PID 12899 | RSS: Main: 172806144 02:32:44 INFO - PID 12899 | 02:35:42 INFO - PID 12899 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51978/tests/dromaeo/cssquery-ext.html) 02:35:43 INFO - PID 12899 | RSS: Main: 175689728 02:35:43 INFO - PID 12899 | 02:38:45 INFO - PID 12899 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-ext.html (next: http://localhost:51978/tests/dromaeo/cssquery-jquery.html) 02:38:45 INFO - PID 12899 | RSS: Main: 172220416 02:38:45 INFO - PID 12899 | 02:41:47 INFO - PID 12899 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51978/tests/dromaeo/cssquery-mootools.html) 02:41:48 INFO - PID 12899 | RSS: Main: 174444544 02:41:48 INFO - PID 12899 | 02:44:49 INFO - PID 12899 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51978/tests/dromaeo/cssquery-prototype.html) 02:44:50 INFO - PID 12899 | RSS: Main: 172969984 02:44:50 INFO - PID 12899 | 02:47:53 INFO - PID 12899 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51978/tests/dromaeo/cssquery-yui.html) 02:47:54 INFO - PID 12899 | RSS: Main: 172888064 02:47:54 INFO - PID 12899 | 02:50:56 INFO - PID 12899 | Cycle 1(1): loaded http://localhost:51978/tests/dromaeo/cssquery-yui.html (next: http://localhost:51978/tests/dromaeo/cssquery-dojo.html) 02:50:56 INFO - PID 12899 | RSS: Main: 177238016 02:50:56 INFO - PID 12899 | 02:50:56 INFO - PID 12899 | __start_tp_report 02:50:56 INFO - PID 12899 | _x_x_mozilla_page_load 02:50:56 INFO - PID 12899 | _x_x_mozilla_page_load_details 02:50:56 INFO - PID 12899 | |i|pagename|runs| 02:50:56 INFO - PID 12899 | |0;dojo.html;3151.669074747152;3152.2749767553464;3173.4605117130086;3179.936401271975;3192.1859925718954;6246.250449946001;6288.874222515558;6333.176687030686;6339.619622822634;6431.163948686676;6280.152179455772;6296.937030629692;6300.495960323175;6305.684715764216;6342.809715708525;5823.126531020339;5835.5915085944;5888.941110588882;5895.9705201473935;5910.995130827748;5634.098544232924;5865.76536938522;5879.67661778602;5961.791337303195;5983.042713165894;5703.572232082611;5707.686077265748;5786.884262314772;5814.302283725959;5864.736086876101;5755.482006619424;5794.5943783935145;5837.445442682938;5848.152017957373;5871.765129394862;5621.213030175779;5698.6865722385255;5701.57238207132;5754.395788442195;5849.678267695275;6300.873982520324;6303.212098487689;6332.525060620472;6371.203599550056;6384.170057892445;6788.422984046362;6855.108835851357;6890.862182756367;6891.931080689179;6903.1716194057035;6868.484863635197;6891.310868913119;6956.860862782716;6969.47728920333;6976.5465244758925;6178.104174894615;6202.875942481215;6308.558400911938;6323.14637523933;6349.555531112824;6962.860742785116;7050.15398152225;7069.187043490065;7087.82280442993;7104.147502299757;6730.923052311607;6738.528303018839;6769.492288078413;6786.4570834333135;6819.181698196248;6662.56693314933;6827.044213810071;6827.48793840464;6871.14110736158;6920.619365934922;6736.898946515708;6742.797716068526;6751.831204219934;6757.662116894136;6763.5941843489545;6758.189017317954;6764.661766911635;6785.660716964132;6786.728530858809;6796.218434880052;5249.317588713443;5262.473752624707;5291.470852914677;5297.993381257549;5320.308359913187;4683.882902927454;4700.6474514410875;4708.905821883612;4715.858524244278;4742.478327383985;5750.568707346965;5831.854203644943;5847.6783776891825;5929.762809487572;5932.93207222704;6723.731050757915;6743.5953842769595;6753.932460675429;6757.189137303555;6760.763373281911;5722.62802917809;5772.509336706427;5793.565482588822;5794.0439827428;5794.391588883106;5785.623934444247;5798.971005144947;5808.709564521757;5810.912836307459;5839;6672.399484046459;6681.9331806682285;6743.52795304324;6758.155230596176;6759.594424334556;6739.0902228198565;6849.1438570178725;6913.827154321081;7001.194862590765;7017.193022802239;6575;6575.769848055295;6586.045023471665;6587.341265873374;6595.868082638224;6669.599824010575;6698.263191049078;6701.66491675424;6719.966400167968;6720;5857.941420585739;6706.463482921257;6778.8983165251575;6790.7623233186605;7026.473014524034;6658.933410665832;6681.599104053772;6771.830704232433;6775.1531058617675;6778;6458.515611329075;6594.10979517759;6656.5340426169705;6676.6995485202315;6784.898226526705;6531.216254049545;6606.174228221473;6611;6612.90080648781;6613.5370524062855;6650.235222949423;6720.630365329954;6746.527743057939;6751.898721519083;6766.05275261454;6281.748730050746;6381.978883378637;6433.388828061326;6443.355664433707;6520.771772987922 02:50:56 INFO - PID 12899 | |1;ext.html;785.2265518464312;807.3500831830376;815.8898548695925;831.7296878514483;832.6502868795105;39782.801085994564;41952.58047419525;41953.58046419535;42027.159456810885;42083.15833683324;39019.804900975454;39280.21439571207;39376.606233937724;39381.21237575247;39422.408663869996;6984.8952265715925;7084.57492550446;7111.146662400519;7111.182214045373;7118.3949364303835;6945.409640180591;7041.612711300876;7042.507024508286;7043.68303426347;7044.506884518086;7009.824754381156;7027.47293952953;7037.331453511908;7038.401735852433;7039.6480175991255;7579.583122928237;7828.491148075385;7828.804279892991;7831.412644051719;7832.53004819707;7732.265434783687;7824.687012519493;7829.373650108035;7832.960835195844;7834.647440865175;37778.43332349989;37916.24167516662;38250.234995299936;38312.61687383118;38472;3497.545319108525;3541.1501239702543;3543.0433782878736;3546.148924258185;3552.129728216578;31250.687493125006;31337.84331078353;31424.214394640087;31466.528002079987;31540.684593154234;5213.504717051874;5254.816081437131;5304.575633949275;5366.651167674088;5369.4362091980565;193424;193447;193471;194579;194615;48463.27305090427;48776.512234877904;48960.26559601609;49162;49278.26082608764;50403;50503.49496505062;51957.740211298704;52643.47356526462;52774.20838687423;15631.68736625261;15697.293621787047;15698.372065117268;15722.842771572137;15745.921270393575;12741.872581274069;12796.296203708886;12855;13337.666558335924;13579.321033948263;13977;13989.160650361011;14114.858851411354;14177.07848989833;14226.715465690628;5592.804251851166;5695.145728140812;5758.798442054508;5808.273965754281;5891.49922256613;1013.0983424751977;1025.2310766924807;1034.9379037257788;1036.1762398892897;1038.096855735515;23016.539669206522;23074.884625576764;23193.304200874;23197.652035219486;23316.53366932652;1111.2388014210292;1129.1869853705318;1136.1649187846926;1137.6985098948785;1142.2917790969652;12094.57668981599;12097.637070887888;12370.195937264049;12376.195547289397;12382.6285211442;237804;239163;239858;240354;240911;44533;45347.54652453499;47582.52417475781;47866;48046.5195348049;1335.5859683498147;1442.9134251944918;1472.8968972171845;1477.3868844429442;1479.7854311124827;27201.455970880867;27645.30886727848;27654.446911061263;27710.584341235302;27862.30344241329;37153.25693486169;37158.44262336013;37282.627173729;37328.813355933045;37422.25155496821;179828;180942;181924;182035;182162;31902.202444938135;32945.505817413235;33110.83444582858;33334.99995000154;33377;43373.3493997584;43413.348799767395;43487.56512434962;43785.12429751451;43962.78018610014;26575.21263063424;26853.194404167905;26935.32661683395;26956.865215673795;27364.315892102855;31338.686613133577;31350.372992539564;31647.36705265836;31954;32459;34663.826680866434;35944;35987.280254395286;36041;36399;30598.235044124074;30686;31000.844995774878;31004.379912402077;31515.84242078775;23019.19432819843;23182.1886233981;23334.88332558394;23374.883125584263;23715 02:50:56 INFO - PID 12899 | |2;jquery.html;565.7652074389129;568.1250873654573;569.1434391241182;570.3754388944104;576.086902259918;21799.237026704068;22711;22716.204932827353;22792.316230513068;22854.542909141826;23320.766792332073;24812.255632331013;24824.87587562064;24941;25154.24537263885;6342.714577843996;6522.445592124675;6524.412802847743;6527.379898909596;6541.705623246965;6409.743610255584;6519.739210431577;6531.771388001397;6532.3467653234775;6538.673066346688;6173.691315434233;6418.807435776934;6426.357364263583;6429.903551446732;6445.065465507499;6394.072859435379;6448.065030570565;6455.096286519891;6461.967690161519;6463.612183269019;5881.2354393928945;5992.550558708071;6115.510759139258;6123.387661233885;6134.447899689049;27682.861585692142;28003.439931201465;28010.85994570014;28125;28161;2018.495376155961;2035.918563257468;2038.225474319749;2047.8566500344982;2049.0164720934035;18716.251349945986;18866;19279.807201927943;19322.71015934762;19605.411837644893;2455.6193789962585;2479.1446950801947;2527.2039307618043;2528.671272734551;2537.4037101281183;117222.41388793087;124093;124629;135760;141953.99999999898;14273.647008563574;14404.927975360266;14585.142868487475;14696.41214351414;14776.374598794124;22617.88691056567;22729.772702273098;22778.65832012521;22791;22792.202272920367;9693.9030609693;9842.901570984342;9995.70012899614;10035.498225088717;10117.190624750003;9186.954065229631;9211.078892110869;9250.352475326663;9256.444613323221;9275.443473391619;8807.603657835412;8973.685920992864;9015.954920225488;9098.024357779217;9131;2728.890844366203;2732.207659778682;2735.712750161244;2753.3116720819794;2767.0868613357543;920.204023519664;930.55798495714;953.1326492891435;968.4237878462303;970.6020531582018;18714.71927921055;18718.921323158258;18887.527811804815;19008.904955475133;19057.428277151706;1006.8036732837027;1007.9496025198711;1020.5560581147224;1021.9386836789816;1035.3994683083793;10301.690949271533;10346.172306215485;10452.111570516592;10542.051215390651;10612.57549698019;429519;429872;435265;435754;452783;103975;104131.4793426028;104701.47649261857;106226;107749;1139.5214010115772;1150.3730466895395;1156.3351073132983;1222.113967373647;1225.197495640357;20507.897460512602;20510.17959281612;20959.05684244243;21052.789472105083;21122.471938201666;56701.716491417275;57602.4239757597;57991;58041.419585803604;59628;71399.64300178674;71666.64166679133;71944.64027679828;72637.63681181561;73268.63365683351;14111.29443527799;14240.786388203978;14242.857571424152;14271.14373137615;14380.85619143837;21883.452913677287;22177.223797167022;22221;22378.552428951654;22479;17717.645647087244;17864.19611117529;17880.105994699952;18023.2790688371;18041.1881465337;15590.532284031497;15862.44481443144;16210.756838647665;16370.836291636931;16724.749128762836;17895.10524473797;17926.10369481547;18100;18122.637547249244;18163.45509634713;13922.09506382082;13967.650808729863;13967.860321396656;14104.929475352968;14156.858431415965;17120.657586848443;17458.127093645013;17607.383741568985;17774;17837.286508539513 02:50:56 INFO - PID 12899 | |3;mootools.html;1328.7342531493703;1396.2390497179038;1422.0685451029576;1439.3019385597984;1442.9567112986608;14626.634334141638;15217.239138043109;15248.618784530392;15258.923705381458;15275.770863437043;15041.172735499544;15046.623834404118;15066.096034237926;15149.469768558076;15177.468788592374;2632.88152033159;2671.973280267197;2690.4888071266496;2701.2706569226298;2707.10665480392;2666.0668765931964;2695.905643302485;2702.337927207837;2705.0532313690246;2706.472237913608;2612.8432294062322;2692.488427198826;2692.6903406108354;2693.0305090167526;2697.757201851833;2590.3912580543556;2608.778253848406;2626.8949242030485;2628.2509484796933;2636;2489.775920167194;2502.912398066077;2529.7217306096313;2530.392705750625;2532.2909585316143;14550.636234094232;14701.411943522246;14706;14751.852481475262;14778;7593.468457207998;7869.645865936047;7893;7903.723369682091;7947.920520794776;5032.471590482984;5248.6588371755715;5356.678599284055;5419.349678038621;5572.275604171472;1850.84267837234;1860.6185731924922;1887.4149013805763;1946.4647222013919;1946.902654867251;23756.168534101223;24640.630390543978;25687.486250275077;25821;25900.481990360087;7043.92956070443;7060.399866011447;7067.57594544329;7165.605891675904;7308.30571095745;19950;20003.699944500844;20004.799952000583;20006.799592024527;20088.69866951997;8027.478213916193;8058.798530036796;8079.151689072681;8181.550014749127;8184.754457366289;7090.787276381717;7318.96340518294;7485.1017877855;7536.924630753732;7537.2462753725285;5523.116301391758;5626.774929002876;5632.887342253132;5648.943510564924;5765.279340082489;1703.5655907743446;1704.5708969147447;1717.7337512685554;1726.7222255530817;1738.3828740797087;868.7046404222468;873.4890089297832;874.5802015032692;883.8541640629353;888.6045709659139;7952.1650226726515;8090.271875531231;8149.755507334789;8223.259906608433;8240.670373185005;695.8260434891278;708.5890183693446;717.4153065251803;722.8373615936478;727.756201672435;4161.375793630979;4197.601227883407;4207.431996680471;4253.574642535722;4274.786260686953;20306.390808275777;21345.25291614786;21377.144914203567;21458.570828583343;21597.56804863894;20535.28126515565;21107.894460527597;21117.68323475149;21141.471463213544;21265.78734212669;10659.786804263871;10757.408342541079;10938.726531836608;10969.29975853744;11013.559457621766;8195.139510351328;8211.753647390588;8248.752537423887;8264.710735124241;8276.83446331082;17881.910590446965;17940;18047.278108875496;18089.638207236047;18687.43937681872;18757;18838.6232275351;18900.432987010412;19143.23427062902;19294.90352548228;12067.275963442222;12127.272363658209;12247.387630618256;12296.508139674313;12336.938315308365;13858.65353366174;13985.160890346611;14013.229272389708;14019.719605608036;14090.154590724589;10879.401632910265;10909.509072091934;10931.125509959344;11104.111671066457;11204.495797689287;10764.138868890626;10877.67366978992;11056.834147487632;11075.83386249223;11215.49530271156;9770.31607787448;9795;9846.163076138608;9864.408135511892;9871.457069861226;7260.673269702982;7292.562446253241;7294.1247050354295;7334.816629584303;7348.96325518369;6978.581285122909;7024.332688394595;7084.929150708427;7103.751368702277;7174.820629484305 02:50:56 INFO - PID 12899 | |4;prototype.html;464.4913819367793;499.69019208090964;507.84003039042676;518.2718280815454;519.1252739134558;6272.121902933587;6289.937100628992;6304.65324407158;6320.525960552954;6398.776042838502;6318.74725010999;6331;6341.8097457076365;6363.186491849824;6366.299707032234;5204.92192617112;5220.738963051851;5239.633225674204;5257.36911570612;5267;5144.717040562768;5148.948510514903;5158.329417175762;5158.845234642967;5176.033107419175;5106.514881086309;5108.565771909392;5119.436861945183;5155.5360017598405;5159.819406320779;5049.141645920203;5105.974470127663;5132.1275383184575;5132.742478092854;5166.1217593008905;4710.670253082286;4713.882152946169;4713.905721885578;4720.716756994591;4730.219513780222;6224.595401298901;6261.154744109578;6286.245650521921;6307.590006649553;6355.142055822451;5802.680852553108;5804.970975145139;5807.361190269067;5843.444872737083;5848.444397782204;6605.141331626905;6625.9668701656665;6653.7671181508895;6666.9333306666795;6712.194536655584;5107.3360463139925;5113.724392645275;5127.589792816603;5146.459621739738;5150.587952963754;6703.754242328874;6725.293844146343;6936.578001509678;6939.548929319629;6990.720371185146;5478.534324582375;5478.671279723149;5513.531349835229;5538;5538.833834984876;5550.833474995757;5567.3040869891265;5583.497485226349;5587.38538760736;5594.748236329375;5963.777425627736;6029.246344206974;6033;6035.758569657164;6071.908921366183;6735.360140786617;6768.593884366954;6773.8983915241315;6780.796576102724;6784.93215067843;1889.9794111179808;1925.8266755992029;1934.951626209328;1949.5321122930395;1953.25776205044;1325.7282257137263;1412.6750847304972;1418.907770994882;1441.6323837421392;1451.2598574726965;5797.188393624896;5802.20131759156;5809.157672137517;5830.125481177857;5866.208061911672;6694.5272040151085;6771.52599318043;6799.456043516507;6805.523613347018;6814.934708109787;5773.913391299134;5782.797602083991;5785.247917770663;5793.623414478046;5825;3568.6074531801482;3617.2403795202777;3632.9636703633155;3635.836387362575;3678.1907980244305;6563.50773691975;6626;6632.900506492407;6645.900311495331;6693.397594216543;6872.931270687329;6889.724411023503;6912.170539535288;6923.792286231421;6974.755883544051;6395.264544577433;6476.967615161894;6503.6097834130105;6525.836854078591;6537.143085579531;6664.333566643297;6768.255491895986;6779.627130504284;6834.555753875982;6835.521513494008;6610.900836487553;6612.702428390638;6675.532712710064;6725.865482690221;6766.4248538874745;6414.775482858077;6425.261094973951;6489.870202595827;6495.220573531206;6597.439217666552;5378.031954248273;5415.268938693226;5434.184872269191;5473.890522189614;5476.644018138808;6378.617282963037;6597.6701164940605;6749.628770417674;6756.527043106936;6906.412954898688;6224.533160012927;6404.647744373918;6485.935140648533;6517.934820651923;6588.901166482411;6425.871482570229;6497.8700425992165;6512.381323774234;6517.480560497278;6527.9020814688765;6488.448481879093;6494;6498.870022599616;6533.346665333429;6545.803625891231;6563.409293163638;6601.801945941629;6616.603003819786;6639.867202656016;6652.068710380454;6289.339619340087;6338.429541341301;6374.298827129105;6381.298057213796;6455.419012288917 02:50:56 INFO - PID 12899 | |5;yui.html;901.6258252825078;912.885889263842;913.9040400757921;918.2149262380665;927.7726956895561;19591.216351345945;20195.293164739236;20252.29116980906;20330.1867925283;20381.796182038175;18060.909695451537;18148.90925545374;18162.273509059654;19142.904285478588;19228.71156932647;951.3245595627096;960.4477425480342;961.8749562556858;964.0696727657809;964.2527041542791;959.9712008639752;986.0386123529537;992.1566668331897;993.4436715439341;993.8857031441404;1042.9895701043006;1043.0925095167177;1044;1044.7283706236358;1046.5865982936734;1033.8811036730797;1034.281174583657;1034.8395998620188;1034.8551202831609;1035.3063447490163;762.1958833430729;763.2138896936165;763.2787016269627;763.5647680821934;764.2014095270456;20515.79484205154;20821.271255506137;20947.68578471324;20953.05711242998;20964.475888102766;21349.573008539897;21412.89293553538;21530;21578.136874525;21589.892050539805;17079.743803842954;17142.14289285543;17268.65462690752;17774.200160992787;17822.37621683248;3729.701623870084;3743.681787048104;3745.3445647011663;3757.6618104370737;3758.1732018955786;139910.30044849814;140249;140841;142116;143884;16789.6642067158;17233;17364.479065628053;17379.13104344778;17555;31268.37463250768;31473.8426307867;32282;32308.35383292321;32759.50860737091;8027.357811375076;8138.348932085419;8168.060673022678;8194.836103277901;8203.466774659748;4107.67138628909;4123.9175216496105;4190.413342132104;4216.768077755692;4236.385724069993;5077.644564880423;5119.334486516729;5136.049830781318;5179.326687530597;5189.766460509286;1065.7815147894662;1080.9135269178446;1092.5138313450439;1100.1034157162048;1100.5252961032095;697.5988806436319;713.2617740638415;717.4117223876372;718.4970520635576;721.1058287723185;11362.318260904372;11479.139064570023;12133.453994570089;13201;13368.39842207103;698.6262349642917;712.2307907459931;719.9712011519586;721.0122132678264;726.4224941171781;5888.617239879394;5930.976649462395;5969.970150149308;5986;6070.362611925684;124765;125147;125429.37285313515;127225;128595;114788.42605786918;115142.4242878797;115180.42409788065;115188.42405787918;116619;92930.53534732418;94475;94522.52738736263;95215.52392237994;96748;10691.144708423151;10767.407792571485;10796.40619765889;10840.349579025284;11117.944410277896;43772;43940.34089488724;44178.337324940796;44326;44957.55042449533;56774.7161264191;57321.42678573161;58980;59166.40833591609;60149;15870.9206453967;15982.280797363914;16154.192290385668;16189.271482783068;16418.917905410395;17444.47666570005;17556.73664895002;17594.648107038043;17638.206280717655;17836.197371118073;16171.67656646884;16271.186440677682;16281.59296017609;16380.180990950643;16433.178341082657;16173.27220275067;16362.590935226715;16407.097609631586;16508.66982660364;16698;15394;15452.690946181237;15623.29695163743;15750.684986300439;15767.842321577096;11283.266587671775;11419.885801141882;11447.599334023269;11502.88497115018;11607.535698571963;11340.036096931854;11497.82753258685;11623.825642615198;11758.764824703629;11895.226810257305 02:50:56 INFO - PID 12899 | __end_tp_report 02:50:56 INFO - PID 12899 | __start_cc_report 02:50:56 INFO - PID 12899 | _x_x_mozilla_cycle_collect,375 02:50:56 INFO - PID 12899 | __end_cc_report 02:50:56 INFO - PID 12899 | __startTimestamp1505382656460__endTimestamp 02:50:56 INFO - PID 12899 | 02:50:56 INFO - PID 12899 | ------- Summary: start ------- 02:50:56 INFO - PID 12899 | Number of tests: 6 02:50:56 INFO - PID 12899 | 02:50:56 INFO - PID 12899 | [#0] dojo.html Cycles:165 Average:6250.80 Median:6531.22 stddev:768.04 (11.8%) stddev-sans-first:730.90 02:50:56 INFO - PID 12899 | Values: 3151.7 3152.3 3173.5 3179.9 3192.2 6246.3 6288.9 6333.2 6339.6 6431.2 6280.2 6296.9 6300.5 6305.7 6342.8 5823.1 5835.6 5888.9 5896.0 5911.0 5634.1 5865.8 5879.7 5961.8 5983.0 5703.6 5707.7 5786.9 5814.3 5864.7 5755.5 5794.6 5837.4 5848.2 5871.8 5621.2 5698.7 5701.6 5754.4 5849.7 6300.9 6303.2 6332.5 6371.2 6384.2 6788.4 6855.1 6890.9 6891.9 6903.2 6868.5 6891.3 6956.9 6969.5 6976.5 6178.1 6202.9 6308.6 6323.1 6349.6 6962.9 7050.2 7069.2 7087.8 7104.1 6730.9 6738.5 6769.5 6786.5 6819.2 6662.6 6827.0 6827.5 6871.1 6920.6 6736.9 6742.8 6751.8 6757.7 6763.6 6758.2 6764.7 6785.7 6786.7 6796.2 5249.3 5262.5 5291.5 5298.0 5320.3 4683.9 4700.6 4708.9 4715.9 4742.5 5750.6 5831.9 5847.7 5929.8 5932.9 6723.7 6743.6 6753.9 6757.2 6760.8 5722.6 5772.5 5793.6 5794.0 5794.4 5785.6 5799.0 5808.7 5810.9 5839.0 6672.4 6681.9 6743.5 6758.2 6759.6 6739.1 6849.1 6913.8 7001.2 7017.2 6575.0 6575.8 6586.0 6587.3 6595.9 6669.6 6698.3 6701.7 6720.0 6720.0 5857.9 6706.5 6778.9 6790.8 7026.5 6658.9 6681.6 6771.8 6775.2 6778.0 6458.5 6594.1 6656.5 6676.7 6784.9 6531.2 6606.2 6611.0 6612.9 6613.5 6650.2 6720.6 6746.5 6751.9 6766.1 6281.7 6382.0 6433.4 6443.4 6520.8 02:50:56 INFO - PID 12899 | 02:50:56 INFO - PID 12899 | [#1] ext.html Cycles:180 Average:37132.91 Median:19080.69 stddev:53520.02 (280.5%) stddev-sans-first:53600.57 02:50:56 INFO - PID 12899 | Values: 785.2 807.4 815.9 831.7 832.7 39782.8 41952.6 41953.6 42027.2 42083.2 39019.8 39280.2 39376.6 39381.2 39422.4 6984.9 7084.6 7111.1 7111.2 7118.4 6945.4 7041.6 7042.5 7043.7 7044.5 7009.8 7027.5 7037.3 7038.4 7039.6 7579.6 7828.5 7828.8 7831.4 7832.5 7732.3 7824.7 7829.4 7833.0 7834.6 37778.4 37916.2 38250.2 38312.6 38472.0 3497.5 3541.2 3543.0 3546.1 3552.1 31250.7 31337.8 31424.2 31466.5 31540.7 5213.5 5254.8 5304.6 5366.7 5369.4 193424.0 193447.0 193471.0 194579.0 194615.0 48463.3 48776.5 48960.3 49162.0 49278.3 50403.0 50503.5 51957.7 52643.5 52774.2 15631.7 15697.3 15698.4 15722.8 15745.9 12741.9 12796.3 12855.0 13337.7 13579.3 13977.0 13989.2 14114.9 14177.1 14226.7 5592.8 5695.1 5758.8 5808.3 5891.5 1013.1 1025.2 1034.9 1036.2 1038.1 23016.5 23074.9 23193.3 23197.7 23316.5 1111.2 1129.2 1136.2 1137.7 1142.3 12094.6 12097.6 12370.2 12376.2 12382.6 237804.0 239163.0 239858.0 240354.0 240911.0 44533.0 45347.5 47582.5 47866.0 48046.5 1335.6 1442.9 1472.9 1477.4 1479.8 27201.5 27645.3 27654.4 27710.6 27862.3 37153.3 37158.4 37282.6 37328.8 37422.3 179828.0 180942.0 181924.0 182035.0 182162.0 31902.2 32945.5 33110.8 33335.0 33377.0 43373.3 43413.3 43487.6 43785.1 43962.8 26575.2 26853.2 26935.3 26956.9 27364.3 31338.7 31350.4 31647.4 31954.0 32459.0 34663.8 35944.0 35987.3 36041.0 36399.0 30598.2 30686.0 31000.8 31004.4 31515.8 23019.2 23182.2 23334.9 23374.9 23715.0 02:50:56 INFO - PID 12899 | 02:50:56 INFO - PID 12899 | [#2] jquery.html Cycles:180 Average:32608.40 Median:22722.99 stddev:73854.17 (325.0%) stddev-sans-first:74022.16 02:50:56 INFO - PID 12899 | Values: 565.8 568.1 569.1 570.4 576.1 21799.2 22711.0 22716.2 22792.3 22854.5 23320.8 24812.3 24824.9 24941.0 25154.2 6342.7 6522.4 6524.4 6527.4 6541.7 6409.7 6519.7 6531.8 6532.3 6538.7 6173.7 6418.8 6426.4 6429.9 6445.1 6394.1 6448.1 6455.1 6462.0 6463.6 5881.2 5992.6 6115.5 6123.4 6134.4 27682.9 28003.4 28010.9 28125.0 28161.0 2018.5 2035.9 2038.2 2047.9 2049.0 18716.3 18866.0 19279.8 19322.7 19605.4 2455.6 2479.1 2527.2 2528.7 2537.4 117222.4 124093.0 124629.0 135760.0 141954.0 14273.6 14404.9 14585.1 14696.4 14776.4 22617.9 22729.8 22778.7 22791.0 22792.2 9693.9 9842.9 9995.7 10035.5 10117.2 9187.0 9211.1 9250.4 9256.4 9275.4 8807.6 8973.7 9016.0 9098.0 9131.0 2728.9 2732.2 2735.7 2753.3 2767.1 920.2 930.6 953.1 968.4 970.6 18714.7 18718.9 18887.5 19008.9 19057.4 1006.8 1007.9 1020.6 1021.9 1035.4 10301.7 10346.2 10452.1 10542.1 10612.6 429519.0 429872.0 435265.0 435754.0 452783.0 103975.0 104131.5 104701.5 106226.0 107749.0 1139.5 1150.4 1156.3 1222.1 1225.2 20507.9 20510.2 20959.1 21052.8 21122.5 56701.7 57602.4 57991.0 58041.4 59628.0 71399.6 71666.6 71944.6 72637.6 73268.6 14111.3 14240.8 14242.9 14271.1 14380.9 21883.5 22177.2 22221.0 22378.6 22479.0 17717.6 17864.2 17880.1 18023.3 18041.2 15590.5 15862.4 16210.8 16370.8 16724.7 17895.1 17926.1 18100.0 18122.6 18163.5 13922.1 13967.7 13967.9 14104.9 14156.9 17120.7 17458.1 17607.4 17774.0 17837.3 02:50:56 INFO - PID 12899 | 02:50:56 INFO - PID 12899 | [#3] mootools.html Cycles:180 Average:9293.78 Median:14254.63 stddev:6631.54 (46.5%) stddev-sans-first:6623.14 02:50:56 INFO - PID 12899 | Values: 1328.7 1396.2 1422.1 1439.3 1443.0 14626.6 15217.2 15248.6 15258.9 15275.8 15041.2 15046.6 15066.1 15149.5 15177.5 2632.9 2672.0 2690.5 2701.3 2707.1 2666.1 2695.9 2702.3 2705.1 2706.5 2612.8 2692.5 2692.7 2693.0 2697.8 2590.4 2608.8 2626.9 2628.3 2636.0 2489.8 2502.9 2529.7 2530.4 2532.3 14550.6 14701.4 14706.0 14751.9 14778.0 7593.5 7869.6 7893.0 7903.7 7947.9 5032.5 5248.7 5356.7 5419.3 5572.3 1850.8 1860.6 1887.4 1946.5 1946.9 23756.2 24640.6 25687.5 25821.0 25900.5 7043.9 7060.4 7067.6 7165.6 7308.3 19950.0 20003.7 20004.8 20006.8 20088.7 8027.5 8058.8 8079.2 8181.6 8184.8 7090.8 7319.0 7485.1 7536.9 7537.2 5523.1 5626.8 5632.9 5648.9 5765.3 1703.6 1704.6 1717.7 1726.7 1738.4 868.7 873.5 874.6 883.9 888.6 7952.2 8090.3 8149.8 8223.3 8240.7 695.8 708.6 717.4 722.8 727.8 4161.4 4197.6 4207.4 4253.6 4274.8 20306.4 21345.3 21377.1 21458.6 21597.6 20535.3 21107.9 21117.7 21141.5 21265.8 10659.8 10757.4 10938.7 10969.3 11013.6 8195.1 8211.8 8248.8 8264.7 8276.8 17881.9 17940.0 18047.3 18089.6 18687.4 18757.0 18838.6 18900.4 19143.2 19294.9 12067.3 12127.3 12247.4 12296.5 12336.9 13858.7 13985.2 14013.2 14019.7 14090.2 10879.4 10909.5 10931.1 11104.1 11204.5 10764.1 10877.7 11056.8 11075.8 11215.5 9770.3 9795.0 9846.2 9864.4 9871.5 7260.7 7292.6 7294.1 7334.8 7349.0 6978.6 7024.3 7084.9 7103.8 7174.8 02:50:56 INFO - PID 12899 | 02:50:56 INFO - PID 12899 | [#4] prototype.html Cycles:180 Average:5639.87 Median:6289.64 stddev:1514.47 (24.1%) stddev-sans-first:1468.06 02:50:56 INFO - PID 12899 | Values: 464.5 499.7 507.8 518.3 519.1 6272.1 6289.9 6304.7 6320.5 6398.8 6318.7 6331.0 6341.8 6363.2 6366.3 5204.9 5220.7 5239.6 5257.4 5267.0 5144.7 5148.9 5158.3 5158.8 5176.0 5106.5 5108.6 5119.4 5155.5 5159.8 5049.1 5106.0 5132.1 5132.7 5166.1 4710.7 4713.9 4713.9 4720.7 4730.2 6224.6 6261.2 6286.2 6307.6 6355.1 5802.7 5805.0 5807.4 5843.4 5848.4 6605.1 6626.0 6653.8 6666.9 6712.2 5107.3 5113.7 5127.6 5146.5 5150.6 6703.8 6725.3 6936.6 6939.5 6990.7 5478.5 5478.7 5513.5 5538.0 5538.8 5550.8 5567.3 5583.5 5587.4 5594.7 5963.8 6029.2 6033.0 6035.8 6071.9 6735.4 6768.6 6773.9 6780.8 6784.9 1890.0 1925.8 1935.0 1949.5 1953.3 1325.7 1412.7 1418.9 1441.6 1451.3 5797.2 5802.2 5809.2 5830.1 5866.2 6694.5 6771.5 6799.5 6805.5 6814.9 5773.9 5782.8 5785.2 5793.6 5825.0 3568.6 3617.2 3633.0 3635.8 3678.2 6563.5 6626.0 6632.9 6645.9 6693.4 6872.9 6889.7 6912.2 6923.8 6974.8 6395.3 6477.0 6503.6 6525.8 6537.1 6664.3 6768.3 6779.6 6834.6 6835.5 6610.9 6612.7 6675.5 6725.9 6766.4 6414.8 6425.3 6489.9 6495.2 6597.4 5378.0 5415.3 5434.2 5473.9 5476.6 6378.6 6597.7 6749.6 6756.5 6906.4 6224.5 6404.6 6485.9 6517.9 6588.9 6425.9 6497.9 6512.4 6517.5 6527.9 6488.4 6494.0 6498.9 6533.3 6545.8 6563.4 6601.8 6616.6 6639.9 6652.1 6289.3 6338.4 6374.3 6381.3 6455.4 02:50:56 INFO - PID 12899 | 02:50:56 INFO - PID 12899 | [#5] yui.html Cycles:180 Average:24776.00 Median:19893.25 stddev:36170.59 (181.8%) stddev-sans-first:36227.64 02:50:56 INFO - PID 12899 | Values: 901.6 912.9 913.9 918.2 927.8 19591.2 20195.3 20252.3 20330.2 20381.8 18060.9 18148.9 18162.3 19142.9 19228.7 951.3 960.4 961.9 964.1 964.3 960.0 986.0 992.2 993.4 993.9 1043.0 1043.1 1044.0 1044.7 1046.6 1033.9 1034.3 1034.8 1034.9 1035.3 762.2 763.2 763.3 763.6 764.2 20515.8 20821.3 20947.7 20953.1 20964.5 21349.6 21412.9 21530.0 21578.1 21589.9 17079.7 17142.1 17268.7 17774.2 17822.4 3729.7 3743.7 3745.3 3757.7 3758.2 139910.3 140249.0 140841.0 142116.0 143884.0 16789.7 17233.0 17364.5 17379.1 17555.0 31268.4 31473.8 32282.0 32308.4 32759.5 8027.4 8138.3 8168.1 8194.8 8203.5 4107.7 4123.9 4190.4 4216.8 4236.4 5077.6 5119.3 5136.0 5179.3 5189.8 1065.8 1080.9 1092.5 1100.1 1100.5 697.6 713.3 717.4 718.5 721.1 11362.3 11479.1 12133.5 13201.0 13368.4 698.6 712.2 720.0 721.0 726.4 5888.6 5931.0 5970.0 5986.0 6070.4 124765.0 125147.0 125429.4 127225.0 128595.0 114788.4 115142.4 115180.4 115188.4 116619.0 92930.5 94475.0 94522.5 95215.5 96748.0 10691.1 10767.4 10796.4 10840.3 11117.9 43772.0 43940.3 44178.3 44326.0 44957.6 56774.7 57321.4 58980.0 59166.4 60149.0 15870.9 15982.3 16154.2 16189.3 16418.9 17444.5 17556.7 17594.6 17638.2 17836.2 16171.7 16271.2 16281.6 16380.2 16433.2 16173.3 16362.6 16407.1 16508.7 16698.0 15394.0 15452.7 15623.3 15750.7 15767.8 11283.3 11419.9 11447.6 11502.9 11607.5 11340.0 11497.8 11623.8 11758.8 11895.2 02:50:56 INFO - PID 12899 | -------- Summary: end -------- 02:50:56 INFO - PID 12899 | 02:50:57 INFO - TEST-INFO | 12899: exit 0 02:50:57 INFO - TEST-OK | dromaeo_css | took 1109528ms 02:50:57 INFO - TEST-START | kraken 02:50:57 INFO - Initialising browser for kraken test... 02:50:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51978/getInfo.html -profile /tmp/tmpuYCDNe/profile 02:50:57 INFO - TEST-INFO | started process 13270 (/builds/slave/test/build/application/firefox/firefox http://localhost:51978/getInfo.html) 02:51:02 INFO - TEST-INFO | 13270: exit 0 02:51:02 INFO - Browser initialized. 02:51:02 INFO - Running cycle 1/1 for kraken test... 02:51:02 INFO - Using env: {'DISPLAY': ':0', 02:51:02 INFO - 'HOME': '/home/cltbld', 02:51:02 INFO - 'JSGC_DISABLE_POISONING': '1', 02:51:02 INFO - 'LANG': 'en_US.UTF-8', 02:51:02 INFO - 'LANGUAGE': 'en_US:en', 02:51:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:51:02 INFO - 'LOGNAME': 'cltbld', 02:51:02 INFO - 'MAIL': '/var/mail/cltbld', 02:51:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:51:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:51:02 INFO - 'MOZ_CRASHREPORTER': '1', 02:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:51:02 INFO - 'MOZ_NO_REMOTE': '1', 02:51:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:51:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:51:02 INFO - 'NO_EM_RESTART': '1', 02:51:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:02 INFO - 'PWD': '/builds/slave/test', 02:51:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:51:02 INFO - 'RUST_BACKTRACE': 'full', 02:51:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:51:02 INFO - 'SHELL': '/bin/bash', 02:51:02 INFO - 'SHLVL': '1', 02:51:02 INFO - 'TERM': 'linux', 02:51:02 INFO - 'TMOUT': '86400', 02:51:02 INFO - 'USER': 'cltbld', 02:51:02 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184', 02:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:02 INFO - '_': '/tools/buildbot/bin/python'} 02:51:02 INFO - TEST-INFO | started process 13501 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuYCDNe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:03 INFO - PID 13501 | 02:51:03 INFO - PID 13501 | (/builds/slave/test/build/application/firefox/firefox:13550): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:51:03 INFO - PID 13501 | 02:51:13 INFO - PID 13501 | RSS: Main: 173281280 02:51:13 INFO - PID 13501 | 02:51:16 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-beat-detection) 02:51:17 INFO - PID 13501 | RSS: Main: 178421760 02:51:17 INFO - PID 13501 | 02:51:20 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-dft) 02:51:20 INFO - PID 13501 | RSS: Main: 178475008 02:51:20 INFO - PID 13501 | 02:51:23 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-fft) 02:51:24 INFO - PID 13501 | RSS: Main: 174215168 02:51:24 INFO - PID 13501 | 02:51:26 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51978/tests/kraken/driver.html?testName=audio-oscillator) 02:51:27 INFO - PID 13501 | RSS: Main: 174678016 02:51:27 INFO - PID 13501 | 02:51:29 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51978/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:51:29 INFO - PID 13501 | RSS: Main: 174972928 02:51:29 INFO - PID 13501 | 02:51:33 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51978/tests/kraken/driver.html?testName=imaging-darkroom) 02:51:33 INFO - PID 13501 | RSS: Main: 177573888 02:51:33 INFO - PID 13501 | 02:51:37 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51978/tests/kraken/driver.html?testName=imaging-desaturate) 02:51:38 INFO - PID 13501 | RSS: Main: 177623040 02:51:38 INFO - PID 13501 | 02:51:41 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51978/tests/kraken/driver.html?testName=json-parse-financial) 02:51:41 INFO - PID 13501 | RSS: Main: 177909760 02:51:41 INFO - PID 13501 | 02:51:43 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51978/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:51:43 INFO - PID 13501 | RSS: Main: 177668096 02:51:43 INFO - PID 13501 | 02:51:45 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:51:46 INFO - PID 13501 | RSS: Main: 177299456 02:51:46 INFO - PID 13501 | 02:51:48 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:51:49 INFO - PID 13501 | RSS: Main: 174514176 02:51:49 INFO - PID 13501 | 02:51:51 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:51:52 INFO - PID 13501 | RSS: Main: 175276032 02:51:52 INFO - PID 13501 | 02:51:55 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:51:55 INFO - PID 13501 | RSS: Main: 175173632 02:51:55 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | Cycle 1(1): loaded http://localhost:51978/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51978/tests/kraken/driver.html?testName=ai-astar) 02:51:57 INFO - PID 13501 | RSS: Main: 176222208 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | __start_tp_report 02:51:57 INFO - PID 13501 | _x_x_mozilla_page_load 02:51:57 INFO - PID 13501 | _x_x_mozilla_page_load_details 02:51:57 INFO - PID 13501 | |i|pagename|runs| 02:51:57 INFO - PID 13501 | |0;ai-astar;110;109;107;107;108;112;105;110;110;110 02:51:57 INFO - PID 13501 | |1;audio-beat-detection;117;122;117;120;118;129;118;120;117;118 02:51:57 INFO - PID 13501 | |2;audio-dft;163;164;162;162;163;165;163;167;184;163 02:51:57 INFO - PID 13501 | |3;audio-fft;73;74;74;72;74;76;73;74;75;75 02:51:57 INFO - PID 13501 | |4;audio-oscillator;79;79;79;79;79;78;79;81;80;78 02:51:57 INFO - PID 13501 | |5;imaging-gaussian-blur;115;114;113;110;109;113;113;114;113;118 02:51:57 INFO - PID 13501 | |6;imaging-darkroom;147;147;147;148;147;147;149;150;148;148 02:51:57 INFO - PID 13501 | |7;imaging-desaturate;95;94;94;94;94;94;94;94;95;94 02:51:57 INFO - PID 13501 | |8;json-parse-financial;55;57;54;54;54;53;53;53;52;53 02:51:57 INFO - PID 13501 | |9;json-stringify-tinderbox;37;37;37;38;38;38;37;37;38;37 02:51:57 INFO - PID 13501 | |10;stanford-crypto-aes;77;75;75;73;77;74;73;73;75;73 02:51:57 INFO - PID 13501 | |11;stanford-crypto-ccm;113;113;118;115;114;116;113;115;117;110 02:51:57 INFO - PID 13501 | |12;stanford-crypto-pbkdf2;167;171;166;176;166;164;165;167;166;169 02:51:57 INFO - PID 13501 | |13;stanford-crypto-sha256-iterative;69;66;66;64;65;65;67;66;66;63 02:51:57 INFO - PID 13501 | __end_tp_report 02:51:57 INFO - PID 13501 | __start_cc_report 02:51:57 INFO - PID 13501 | _x_x_mozilla_cycle_collect,961 02:51:57 INFO - PID 13501 | __end_cc_report 02:51:57 INFO - PID 13501 | __startTimestamp1505382717927__endTimestamp 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | ------- Summary: start ------- 02:51:57 INFO - PID 13501 | Number of tests: 14 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.00 stddev:2.04 (1.9%) stddev-sans-first:2.12 02:51:57 INFO - PID 13501 | Values: 110.0 109.0 107.0 107.0 108.0 112.0 105.0 110.0 110.0 110.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#1] audio-beat-detection Cycles:10 Average:119.60 Median:119.00 stddev:3.69 (3.1%) stddev-sans-first:3.79 02:51:57 INFO - PID 13501 | Values: 117.0 122.0 117.0 120.0 118.0 129.0 118.0 120.0 117.0 118.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#2] audio-dft Cycles:10 Average:165.60 Median:163.50 stddev:6.64 (4.1%) stddev-sans-first:6.97 02:51:57 INFO - PID 13501 | Values: 163.0 164.0 162.0 162.0 163.0 165.0 163.0 167.0 184.0 163.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#3] audio-fft Cycles:10 Average:74.00 Median:74.00 stddev:1.15 (1.6%) stddev-sans-first:1.17 02:51:57 INFO - PID 13501 | Values: 73.0 74.0 74.0 72.0 74.0 76.0 73.0 74.0 75.0 75.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#4] audio-oscillator Cycles:10 Average:79.10 Median:79.00 stddev:0.88 (1.1%) stddev-sans-first:0.93 02:51:57 INFO - PID 13501 | Values: 79.0 79.0 79.0 79.0 79.0 78.0 79.0 81.0 80.0 78.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:113.50 stddev:2.49 (2.2%) stddev-sans-first:2.55 02:51:57 INFO - PID 13501 | Values: 115.0 114.0 113.0 110.0 109.0 113.0 113.0 114.0 113.0 118.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#6] imaging-darkroom Cycles:10 Average:147.80 Median:148.00 stddev:1.03 (0.7%) stddev-sans-first:1.05 02:51:57 INFO - PID 13501 | Values: 147.0 147.0 147.0 148.0 147.0 147.0 149.0 150.0 148.0 148.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 02:51:57 INFO - PID 13501 | Values: 95.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.40 (2.6%) stddev-sans-first:1.41 02:51:57 INFO - PID 13501 | Values: 55.0 57.0 54.0 54.0 54.0 53.0 53.0 53.0 52.0 53.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#9] json-stringify-tinderbox Cycles:10 Average:37.40 Median:37.50 stddev:0.52 (1.4%) stddev-sans-first:0.53 02:51:57 INFO - PID 13501 | Values: 37.0 37.0 37.0 38.0 38.0 38.0 37.0 37.0 38.0 37.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:75.00 stddev:1.58 (2.1%) stddev-sans-first:1.39 02:51:57 INFO - PID 13501 | Values: 77.0 75.0 75.0 73.0 77.0 74.0 73.0 73.0 75.0 73.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#11] stanford-crypto-ccm Cycles:10 Average:114.40 Median:115.00 stddev:2.32 (2.0%) stddev-sans-first:2.40 02:51:57 INFO - PID 13501 | Values: 113.0 113.0 118.0 115.0 114.0 116.0 113.0 115.0 117.0 110.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:167.00 stddev:3.53 (2.1%) stddev-sans-first:3.73 02:51:57 INFO - PID 13501 | Values: 167.0 171.0 166.0 176.0 166.0 164.0 165.0 167.0 166.0 169.0 02:51:57 INFO - PID 13501 | 02:51:57 INFO - PID 13501 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.64 (2.5%) stddev-sans-first:1.22 02:51:57 INFO - PID 13501 | Values: 69.0 66.0 66.0 64.0 65.0 65.0 67.0 66.0 66.0 63.0 02:51:57 INFO - PID 13501 | -------- Summary: end -------- 02:51:57 INFO - PID 13501 | 02:51:58 INFO - PID 13501 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 02:51:58 INFO - TEST-INFO | 13501: exit 0 02:51:58 INFO - TEST-OK | kraken | took 61471ms 02:51:58 INFO - SUITE-END | took 1171s 02:51:59 INFO - Completed test suite (00:19:31) 02:51:59 INFO - javascript benchmark 02:51:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8515.581441366043, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3151.669074747152, 3152.2749767553464, 3173.4605117130086, 3179.936401271975, 3192.1859925718954, 6246.250449946001, 6288.874222515558, 6333.176687030686, 6339.619622822634, 6431.163948686676, 6280.152179455772, 6296.937030629692, 6300.495960323175, 6305.684715764216, 6342.809715708525, 5823.126531020339, 5835.5915085944, 5888.941110588882, 5895.9705201473935, 5910.995130827748, 5634.098544232924, 5865.76536938522, 5879.67661778602, 5961.791337303195, 5983.042713165894, 5703.572232082611, 5707.686077265748, 5786.884262314772, 5814.302283725959, 5864.736086876101, 5755.482006619424, 5794.5943783935145, 5837.445442682938, 5848.152017957373, 5871.765129394862, 5621.213030175779, 5698.6865722385255, 5701.57238207132, 5754.395788442195, 5849.678267695275, 6300.873982520324, 6303.212098487689, 6332.525060620472, 6371.203599550056, 6384.170057892445, 6788.422984046362, 6855.108835851357, 6890.862182756367, 6891.931080689179, 6903.1716194057035, 6868.484863635197, 6891.310868913119, 6956.860862782716, 6969.47728920333, 6976.5465244758925, 6178.104174894615, 6202.875942481215, 6308.558400911938, 6323.14637523933, 6349.555531112824, 6962.860742785116, 7050.15398152225, 7069.187043490065, 7087.82280442993, 7104.147502299757, 6730.923052311607, 6738.528303018839, 6769.492288078413, 6786.4570834333135, 6819.181698196248, 6662.56693314933, 6827.044213810071, 6827.48793840464, 6871.14110736158, 6920.619365934922, 6736.898946515708, 6742.797716068526, 6751.831204219934, 6757.662116894136, 6763.5941843489545, 6758.189017317954, 6764.661766911635, 6785.660716964132, 6786.728530858809, 6796.218434880052, 5249.317588713443, 5262.473752624707, 5291.470852914677, 5297.993381257549, 5320.308359913187, 4683.882902927454, 4700.6474514410875, 4708.905821883612, 4715.858524244278, 4742.478327383985, 5750.568707346965, 5831.854203644943, 5847.6783776891825, 5929.762809487572, 5932.93207222704, 6723.731050757915, 6743.5953842769595, 6753.932460675429, 6757.189137303555, 6760.763373281911, 5722.62802917809, 5772.509336706427, 5793.565482588822, 5794.0439827428, 5794.391588883106, 5785.623934444247, 5798.971005144947, 5808.709564521757, 5810.912836307459, 5839.0, 6672.399484046459, 6681.9331806682285, 6743.52795304324, 6758.155230596176, 6759.594424334556, 6739.0902228198565, 6849.1438570178725, 6913.827154321081, 7001.194862590765, 7017.193022802239, 6575.0, 6575.769848055295, 6586.045023471665, 6587.341265873374, 6595.868082638224, 6669.599824010575, 6698.263191049078, 6701.66491675424, 6719.966400167968, 6720.0, 5857.941420585739, 6706.463482921257, 6778.8983165251575, 6790.7623233186605, 7026.473014524034, 6658.933410665832, 6681.599104053772, 6771.830704232433, 6775.1531058617675, 6778.0, 6458.515611329075, 6594.10979517759, 6656.5340426169705, 6676.6995485202315, 6784.898226526705, 6531.216254049545, 6606.174228221473, 6611.0, 6612.90080648781, 6613.5370524062855, 6650.235222949423, 6720.630365329954, 6746.527743057939, 6751.898721519083, 6766.05275261454, 6281.748730050746, 6381.978883378637, 6433.388828061326, 6443.355664433707, 6520.771772987922], "value": 6191.597627727459, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [785.2265518464312, 807.3500831830376, 815.8898548695925, 831.7296878514483, 832.6502868795105, 39782.801085994564, 41952.58047419525, 41953.58046419535, 42027.159456810885, 42083.15833683324, 39019.804900975454, 39280.21439571207, 39376.606233937724, 39381.21237575247, 39422.408663869996, 6984.8952265715925, 7084.57492550446, 7111.146662400519, 7111.182214045373, 7118.3949364303835, 6945.409640180591, 7041.612711300876, 7042.507024508286, 7043.68303426347, 7044.506884518086, 7009.824754381156, 7027.47293952953, 7037.331453511908, 7038.401735852433, 7039.6480175991255, 7579.583122928237, 7828.491148075385, 7828.804279892991, 7831.412644051719, 7832.53004819707, 7732.265434783687, 7824.687012519493, 7829.373650108035, 7832.960835195844, 7834.647440865175, 37778.43332349989, 37916.24167516662, 38250.234995299936, 38312.61687383118, 38472.0, 3497.545319108525, 3541.1501239702543, 3543.0433782878736, 3546.148924258185, 3552.129728216578, 31250.687493125006, 31337.84331078353, 31424.214394640087, 31466.528002079987, 31540.684593154234, 5213.504717051874, 5254.816081437131, 5304.575633949275, 5366.651167674088, 5369.4362091980565, 193424.0, 193447.0, 193471.0, 194579.0, 194615.0, 48463.27305090427, 48776.512234877904, 48960.26559601609, 49162.0, 49278.26082608764, 50403.0, 50503.49496505062, 51957.740211298704, 52643.47356526462, 52774.20838687423, 15631.68736625261, 15697.293621787047, 15698.372065117268, 15722.842771572137, 15745.921270393575, 12741.872581274069, 12796.296203708886, 12855.0, 13337.666558335924, 13579.321033948263, 13977.0, 13989.160650361011, 14114.858851411354, 14177.07848989833, 14226.715465690628, 5592.804251851166, 5695.145728140812, 5758.798442054508, 5808.273965754281, 5891.49922256613, 1013.0983424751977, 1025.2310766924807, 1034.9379037257788, 1036.1762398892897, 1038.096855735515, 23016.539669206522, 23074.884625576764, 23193.304200874, 23197.652035219486, 23316.53366932652, 1111.2388014210292, 1129.1869853705318, 1136.1649187846926, 1137.6985098948785, 1142.2917790969652, 12094.57668981599, 12097.637070887888, 12370.195937264049, 12376.195547289397, 12382.6285211442, 237804.0, 239163.0, 239858.0, 240354.0, 240911.0, 44533.0, 45347.54652453499, 47582.52417475781, 47866.0, 48046.5195348049, 1335.5859683498147, 1442.9134251944918, 1472.8968972171845, 1477.3868844429442, 1479.7854311124827, 27201.455970880867, 27645.30886727848, 27654.446911061263, 27710.584341235302, 27862.30344241329, 37153.25693486169, 37158.44262336013, 37282.627173729, 37328.813355933045, 37422.25155496821, 179828.0, 180942.0, 181924.0, 182035.0, 182162.0, 31902.202444938135, 32945.505817413235, 33110.83444582858, 33334.99995000154, 33377.0, 43373.3493997584, 43413.348799767395, 43487.56512434962, 43785.12429751451, 43962.78018610014, 26575.21263063424, 26853.194404167905, 26935.32661683395, 26956.865215673795, 27364.315892102855, 31338.686613133577, 31350.372992539564, 31647.36705265836, 31954.0, 32459.0, 34663.826680866434, 35944.0, 35987.280254395286, 36041.0, 36399.0, 30598.235044124074, 30686.0, 31000.844995774878, 31004.379912402077, 31515.84242078775, 23019.19432819843, 23182.1886233981, 23334.88332558394, 23374.883125584263, 23715.0], "value": 16912.283029771774, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [565.7652074389129, 568.1250873654573, 569.1434391241182, 570.3754388944104, 576.086902259918, 21799.237026704068, 22711.0, 22716.204932827353, 22792.316230513068, 22854.542909141826, 23320.766792332073, 24812.255632331013, 24824.87587562064, 24941.0, 25154.24537263885, 6342.714577843996, 6522.445592124675, 6524.412802847743, 6527.379898909596, 6541.705623246965, 6409.743610255584, 6519.739210431577, 6531.771388001397, 6532.3467653234775, 6538.673066346688, 6173.691315434233, 6418.807435776934, 6426.357364263583, 6429.903551446732, 6445.065465507499, 6394.072859435379, 6448.065030570565, 6455.096286519891, 6461.967690161519, 6463.612183269019, 5881.2354393928945, 5992.550558708071, 6115.510759139258, 6123.387661233885, 6134.447899689049, 27682.861585692142, 28003.439931201465, 28010.85994570014, 28125.0, 28161.0, 2018.495376155961, 2035.918563257468, 2038.225474319749, 2047.8566500344982, 2049.0164720934035, 18716.251349945986, 18866.0, 19279.807201927943, 19322.71015934762, 19605.411837644893, 2455.6193789962585, 2479.1446950801947, 2527.2039307618043, 2528.671272734551, 2537.4037101281183, 117222.41388793087, 124093.0, 124629.0, 135760.0, 141953.99999999898, 14273.647008563574, 14404.927975360266, 14585.142868487475, 14696.41214351414, 14776.374598794124, 22617.88691056567, 22729.772702273098, 22778.65832012521, 22791.0, 22792.202272920367, 9693.9030609693, 9842.901570984342, 9995.70012899614, 10035.498225088717, 10117.190624750003, 9186.954065229631, 9211.078892110869, 9250.352475326663, 9256.444613323221, 9275.443473391619, 8807.603657835412, 8973.685920992864, 9015.954920225488, 9098.024357779217, 9131.0, 2728.890844366203, 2732.207659778682, 2735.712750161244, 2753.3116720819794, 2767.0868613357543, 920.204023519664, 930.55798495714, 953.1326492891435, 968.4237878462303, 970.6020531582018, 18714.71927921055, 18718.921323158258, 18887.527811804815, 19008.904955475133, 19057.428277151706, 1006.8036732837027, 1007.9496025198711, 1020.5560581147224, 1021.9386836789816, 1035.3994683083793, 10301.690949271533, 10346.172306215485, 10452.111570516592, 10542.051215390651, 10612.57549698019, 429519.0, 429872.0, 435265.0, 435754.0, 452783.0, 103975.0, 104131.4793426028, 104701.47649261857, 106226.0, 107749.0, 1139.5214010115772, 1150.3730466895395, 1156.3351073132983, 1222.113967373647, 1225.197495640357, 20507.897460512602, 20510.17959281612, 20959.05684244243, 21052.789472105083, 21122.471938201666, 56701.716491417275, 57602.4239757597, 57991.0, 58041.419585803604, 59628.0, 71399.64300178674, 71666.64166679133, 71944.64027679828, 72637.63681181561, 73268.63365683351, 14111.29443527799, 14240.786388203978, 14242.857571424152, 14271.14373137615, 14380.85619143837, 21883.452913677287, 22177.223797167022, 22221.0, 22378.552428951654, 22479.0, 17717.645647087244, 17864.19611117529, 17880.105994699952, 18023.2790688371, 18041.1881465337, 15590.532284031497, 15862.44481443144, 16210.756838647665, 16370.836291636931, 16724.749128762836, 17895.10524473797, 17926.10369481547, 18100.0, 18122.637547249244, 18163.45509634713, 13922.09506382082, 13967.650808729863, 13967.860321396656, 14104.929475352968, 14156.858431415965, 17120.657586848443, 17458.127093645013, 17607.383741568985, 17774.0, 17837.286508539513], "value": 11742.058701581545, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.7342531493703, 1396.2390497179038, 1422.0685451029576, 1439.3019385597984, 1442.9567112986608, 14626.634334141638, 15217.239138043109, 15248.618784530392, 15258.923705381458, 15275.770863437043, 15041.172735499544, 15046.623834404118, 15066.096034237926, 15149.469768558076, 15177.468788592374, 2632.88152033159, 2671.973280267197, 2690.4888071266496, 2701.2706569226298, 2707.10665480392, 2666.0668765931964, 2695.905643302485, 2702.337927207837, 2705.0532313690246, 2706.472237913608, 2612.8432294062322, 2692.488427198826, 2692.6903406108354, 2693.0305090167526, 2697.757201851833, 2590.3912580543556, 2608.778253848406, 2626.8949242030485, 2628.2509484796933, 2636.0, 2489.775920167194, 2502.912398066077, 2529.7217306096313, 2530.392705750625, 2532.2909585316143, 14550.636234094232, 14701.411943522246, 14706.0, 14751.852481475262, 14778.0, 7593.468457207998, 7869.645865936047, 7893.0, 7903.723369682091, 7947.920520794776, 5032.471590482984, 5248.6588371755715, 5356.678599284055, 5419.349678038621, 5572.275604171472, 1850.84267837234, 1860.6185731924922, 1887.4149013805763, 1946.4647222013919, 1946.902654867251, 23756.168534101223, 24640.630390543978, 25687.486250275077, 25821.0, 25900.481990360087, 7043.92956070443, 7060.399866011447, 7067.57594544329, 7165.605891675904, 7308.30571095745, 19950.0, 20003.699944500844, 20004.799952000583, 20006.799592024527, 20088.69866951997, 8027.478213916193, 8058.798530036796, 8079.151689072681, 8181.550014749127, 8184.754457366289, 7090.787276381717, 7318.96340518294, 7485.1017877855, 7536.924630753732, 7537.2462753725285, 5523.116301391758, 5626.774929002876, 5632.887342253132, 5648.943510564924, 5765.279340082489, 1703.5655907743446, 1704.5708969147447, 1717.7337512685554, 1726.7222255530817, 1738.3828740797087, 868.7046404222468, 873.4890089297832, 874.5802015032692, 883.8541640629353, 888.6045709659139, 7952.1650226726515, 8090.271875531231, 8149.755507334789, 8223.259906608433, 8240.670373185005, 695.8260434891278, 708.5890183693446, 717.4153065251803, 722.8373615936478, 727.756201672435, 4161.375793630979, 4197.601227883407, 4207.431996680471, 4253.574642535722, 4274.786260686953, 20306.390808275777, 21345.25291614786, 21377.144914203567, 21458.570828583343, 21597.56804863894, 20535.28126515565, 21107.894460527597, 21117.68323475149, 21141.471463213544, 21265.78734212669, 10659.786804263871, 10757.408342541079, 10938.726531836608, 10969.29975853744, 11013.559457621766, 8195.139510351328, 8211.753647390588, 8248.752537423887, 8264.710735124241, 8276.83446331082, 17881.910590446965, 17940.0, 18047.278108875496, 18089.638207236047, 18687.43937681872, 18757.0, 18838.6232275351, 18900.432987010412, 19143.23427062902, 19294.90352548228, 12067.275963442222, 12127.272363658209, 12247.387630618256, 12296.508139674313, 12336.938315308365, 13858.65353366174, 13985.160890346611, 14013.229272389708, 14019.719605608036, 14090.154590724589, 10879.401632910265, 10909.509072091934, 10931.125509959344, 11104.111671066457, 11204.495797689287, 10764.138868890626, 10877.67366978992, 11056.834147487632, 11075.83386249223, 11215.49530271156, 9770.31607787448, 9795.0, 9846.163076138608, 9864.408135511892, 9871.457069861226, 7260.673269702982, 7292.562446253241, 7294.1247050354295, 7334.816629584303, 7348.96325518369, 6978.581285122909, 7024.332688394595, 7084.929150708427, 7103.751368702277, 7174.820629484305], "value": 6595.031968583751, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.4913819367793, 499.69019208090964, 507.84003039042676, 518.2718280815454, 519.1252739134558, 6272.121902933587, 6289.937100628992, 6304.65324407158, 6320.525960552954, 6398.776042838502, 6318.74725010999, 6331.0, 6341.8097457076365, 6363.186491849824, 6366.299707032234, 5204.92192617112, 5220.738963051851, 5239.633225674204, 5257.36911570612, 5267.0, 5144.717040562768, 5148.948510514903, 5158.329417175762, 5158.845234642967, 5176.033107419175, 5106.514881086309, 5108.565771909392, 5119.436861945183, 5155.5360017598405, 5159.819406320779, 5049.141645920203, 5105.974470127663, 5132.1275383184575, 5132.742478092854, 5166.1217593008905, 4710.670253082286, 4713.882152946169, 4713.905721885578, 4720.716756994591, 4730.219513780222, 6224.595401298901, 6261.154744109578, 6286.245650521921, 6307.590006649553, 6355.142055822451, 5802.680852553108, 5804.970975145139, 5807.361190269067, 5843.444872737083, 5848.444397782204, 6605.141331626905, 6625.9668701656665, 6653.7671181508895, 6666.9333306666795, 6712.194536655584, 5107.3360463139925, 5113.724392645275, 5127.589792816603, 5146.459621739738, 5150.587952963754, 6703.754242328874, 6725.293844146343, 6936.578001509678, 6939.548929319629, 6990.720371185146, 5478.534324582375, 5478.671279723149, 5513.531349835229, 5538.0, 5538.833834984876, 5550.833474995757, 5567.3040869891265, 5583.497485226349, 5587.38538760736, 5594.748236329375, 5963.777425627736, 6029.246344206974, 6033.0, 6035.758569657164, 6071.908921366183, 6735.360140786617, 6768.593884366954, 6773.8983915241315, 6780.796576102724, 6784.93215067843, 1889.9794111179808, 1925.8266755992029, 1934.951626209328, 1949.5321122930395, 1953.25776205044, 1325.7282257137263, 1412.6750847304972, 1418.907770994882, 1441.6323837421392, 1451.2598574726965, 5797.188393624896, 5802.20131759156, 5809.157672137517, 5830.125481177857, 5866.208061911672, 6694.5272040151085, 6771.52599318043, 6799.456043516507, 6805.523613347018, 6814.934708109787, 5773.913391299134, 5782.797602083991, 5785.247917770663, 5793.623414478046, 5825.0, 3568.6074531801482, 3617.2403795202777, 3632.9636703633155, 3635.836387362575, 3678.1907980244305, 6563.50773691975, 6626.0, 6632.900506492407, 6645.900311495331, 6693.397594216543, 6872.931270687329, 6889.724411023503, 6912.170539535288, 6923.792286231421, 6974.755883544051, 6395.264544577433, 6476.967615161894, 6503.6097834130105, 6525.836854078591, 6537.143085579531, 6664.333566643297, 6768.255491895986, 6779.627130504284, 6834.555753875982, 6835.521513494008, 6610.900836487553, 6612.702428390638, 6675.532712710064, 6725.865482690221, 6766.4248538874745, 6414.775482858077, 6425.261094973951, 6489.870202595827, 6495.220573531206, 6597.439217666552, 5378.031954248273, 5415.268938693226, 5434.184872269191, 5473.890522189614, 5476.644018138808, 6378.617282963037, 6597.6701164940605, 6749.628770417674, 6756.527043106936, 6906.412954898688, 6224.533160012927, 6404.647744373918, 6485.935140648533, 6517.934820651923, 6588.901166482411, 6425.871482570229, 6497.8700425992165, 6512.381323774234, 6517.480560497278, 6527.9020814688765, 6488.448481879093, 6494.0, 6498.870022599616, 6533.346665333429, 6545.803625891231, 6563.409293163638, 6601.801945941629, 6616.603003819786, 6639.867202656016, 6652.068710380454, 6289.339619340087, 6338.429541341301, 6374.298827129105, 6381.298057213796, 6455.419012288917], "value": 5199.303171967543, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [901.6258252825078, 912.885889263842, 913.9040400757921, 918.2149262380665, 927.7726956895561, 19591.216351345945, 20195.293164739236, 20252.29116980906, 20330.1867925283, 20381.796182038175, 18060.909695451537, 18148.90925545374, 18162.273509059654, 19142.904285478588, 19228.71156932647, 951.3245595627096, 960.4477425480342, 961.8749562556858, 964.0696727657809, 964.2527041542791, 959.9712008639752, 986.0386123529537, 992.1566668331897, 993.4436715439341, 993.8857031441404, 1042.9895701043006, 1043.0925095167177, 1044.0, 1044.7283706236358, 1046.5865982936734, 1033.8811036730797, 1034.281174583657, 1034.8395998620188, 1034.8551202831609, 1035.3063447490163, 762.1958833430729, 763.2138896936165, 763.2787016269627, 763.5647680821934, 764.2014095270456, 20515.79484205154, 20821.271255506137, 20947.68578471324, 20953.05711242998, 20964.475888102766, 21349.573008539897, 21412.89293553538, 21530.0, 21578.136874525, 21589.892050539805, 17079.743803842954, 17142.14289285543, 17268.65462690752, 17774.200160992787, 17822.37621683248, 3729.701623870084, 3743.681787048104, 3745.3445647011663, 3757.6618104370737, 3758.1732018955786, 139910.30044849814, 140249.0, 140841.0, 142116.0, 143884.0, 16789.6642067158, 17233.0, 17364.479065628053, 17379.13104344778, 17555.0, 31268.37463250768, 31473.8426307867, 32282.0, 32308.35383292321, 32759.50860737091, 8027.357811375076, 8138.348932085419, 8168.060673022678, 8194.836103277901, 8203.466774659748, 4107.67138628909, 4123.9175216496105, 4190.413342132104, 4216.768077755692, 4236.385724069993, 5077.644564880423, 5119.334486516729, 5136.049830781318, 5179.326687530597, 5189.766460509286, 1065.7815147894662, 1080.9135269178446, 1092.5138313450439, 1100.1034157162048, 1100.5252961032095, 697.5988806436319, 713.2617740638415, 717.4117223876372, 718.4970520635576, 721.1058287723185, 11362.318260904372, 11479.139064570023, 12133.453994570089, 13201.0, 13368.39842207103, 698.6262349642917, 712.2307907459931, 719.9712011519586, 721.0122132678264, 726.4224941171781, 5888.617239879394, 5930.976649462395, 5969.970150149308, 5986.0, 6070.362611925684, 124765.0, 125147.0, 125429.37285313515, 127225.0, 128595.0, 114788.42605786918, 115142.4242878797, 115180.42409788065, 115188.42405787918, 116619.0, 92930.53534732418, 94475.0, 94522.52738736263, 95215.52392237994, 96748.0, 10691.144708423151, 10767.407792571485, 10796.40619765889, 10840.349579025284, 11117.944410277896, 43772.0, 43940.34089488724, 44178.337324940796, 44326.0, 44957.55042449533, 56774.7161264191, 57321.42678573161, 58980.0, 59166.40833591609, 60149.0, 15870.9206453967, 15982.280797363914, 16154.192290385668, 16189.271482783068, 16418.917905410395, 17444.47666570005, 17556.73664895002, 17594.648107038043, 17638.206280717655, 17836.197371118073, 16171.67656646884, 16271.186440677682, 16281.59296017609, 16380.180990950643, 16433.178341082657, 16173.27220275067, 16362.590935226715, 16407.097609631586, 16508.66982660364, 16698.0, 15394.0, 15452.690946181237, 15623.29695163743, 15750.684986300439, 15767.842321577096, 11283.266587671775, 11419.885801141882, 11447.599334023269, 11502.88497115018, 11607.535698571963, 11340.036096931854, 11497.82753258685, 11623.825642615198, 11758.764824703629, 11895.226810257305], "value": 9043.788110969548, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1415.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 107.0, 107.0, 108.0, 112.0, 105.0, 110.0, 110.0, 110.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 122.0, 117.0, 120.0, 118.0, 129.0, 118.0, 120.0, 117.0, 118.0], "value": 119.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 162.0, 162.0, 163.0, 165.0, 163.0, 167.0, 184.0, 163.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 74.0, 72.0, 74.0, 76.0, 73.0, 74.0, 75.0, 75.0], "value": 74.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 79.0, 78.0, 79.0, 81.0, 80.0, 78.0], "value": 79.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 110.0, 109.0, 113.0, 113.0, 114.0, 113.0, 118.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 149.0, 150.0, 148.0, 148.0], "value": 147.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 54.0, 54.0, 54.0, 53.0, 53.0, 53.0, 52.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 38.0, 38.0, 38.0, 37.0, 37.0, 38.0, 37.0], "value": 37.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 73.0, 77.0, 74.0, 73.0, 73.0, 75.0, 73.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 118.0, 115.0, 114.0, 116.0, 113.0, 115.0, 117.0, 110.0], "value": 114.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 171.0, 166.0, 176.0, 166.0, 164.0, 165.0, 167.0, 166.0, 169.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 66.0, 64.0, 65.0, 65.0, 67.0, 66.0, 66.0, 63.0], "value": 65.7, "unit": "score"}]}]} 02:51:59 INFO - Return code: 0 02:51:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:51:59 INFO - # TBPL SUCCESS # 02:51:59 INFO - Running post-action listener: _package_coverage_data 02:51:59 INFO - Running post-action listener: _resource_record_post_action 02:51:59 INFO - [mozharness: 2017-09-14 09:51:59.212951Z] Finished run-tests step (success) 02:51:59 INFO - Running post-run listener: _resource_record_post_run 02:51:59 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 8192; Write bytes: 347508736; Read time: 248; Write time: 268856 02:51:59 INFO - TinderboxPrint: CPU usage
13.1% 02:51:59 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 248 02:51:59 INFO - TinderboxPrint: I/O write bytes / time
347,508,736 / 268,856 02:51:59 INFO - TinderboxPrint: CPU idle
8,247.1 (86.9%) 02:51:59 INFO - TinderboxPrint: CPU user
1,195.4 (12.6%) 02:51:59 INFO - TinderboxPrint: Swap in / out
0 / 0 02:51:59 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 13156352; Read time: 0; Write time: 2172 02:51:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 02:51:59 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 333946880; Read time: 240; Write time: 266640 02:51:59 INFO - Running post-run listener: _upload_blobber_files 02:51:59 INFO - Blob upload gear active. 02:51:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:51:59 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 02:51:59 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'] 02:51:59 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 02:52:00 INFO - (blobuploader) - INFO - Open directory for files ... 02:52:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:52:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:52:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:08 INFO - (blobuploader) - INFO - Done attempting. 02:52:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:52:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:52:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:09 INFO - (blobuploader) - INFO - Done attempting. 02:52:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:52:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:52:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:10 INFO - (blobuploader) - INFO - Done attempting. 02:52:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:52:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:52:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:11 INFO - (blobuploader) - INFO - Done attempting. 02:52:11 INFO - (blobuploader) - INFO - Iteration through files over. 02:52:11 INFO - Return code: 0 02:52:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:52:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:52:11 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e632fac588e56117c9204c5adcd4bd9ffa106e6cf64a9a6dc494da14511a2eaaa4c11eaedb17bc16949a70e9936e04605fde34a2da155696e531867a39086620", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a5272c0c18ccec82a5b37f37f1b0cba33639bb8819935d927f6f79d78ed5ca390229e6a4eeba34e66d91aaab098b705952e9f4e261739af9c3d07bc529d15d1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5697439e9b07cd297fbe0417e91599a6eb04b736b503c7267eb69e32a20249938d29c1dc43fd8c102ec5fdfb3f513c936d36503457d9630531fa887d915bb38", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1054671bbd10a896aa6d07bd9b7425c903c39177a086dcbfa0902720dcb22c67f09568d3c673c9dd9698f8e2543e8134da181ec21e0d4b931eedb2a1653e555a"} 02:52:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:52:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:52:11 INFO - Contents: 02:52:11 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e632fac588e56117c9204c5adcd4bd9ffa106e6cf64a9a6dc494da14511a2eaaa4c11eaedb17bc16949a70e9936e04605fde34a2da155696e531867a39086620", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a5272c0c18ccec82a5b37f37f1b0cba33639bb8819935d927f6f79d78ed5ca390229e6a4eeba34e66d91aaab098b705952e9f4e261739af9c3d07bc529d15d1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5697439e9b07cd297fbe0417e91599a6eb04b736b503c7267eb69e32a20249938d29c1dc43fd8c102ec5fdfb3f513c936d36503457d9630531fa887d915bb38", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1054671bbd10a896aa6d07bd9b7425c903c39177a086dcbfa0902720dcb22c67f09568d3c673c9dd9698f8e2543e8134da181ec21e0d4b931eedb2a1653e555a"} 02:52:11 INFO - Running post-run listener: copy_logs_to_upload_dir 02:52:11 INFO - Copying logs to upload dir... 02:52:11 INFO - mkdir: /builds/slave/test/build/upload/logs 02:52:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.956244 ========= master_lag: 0.76 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-14 02:52:11.295773) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 02:52:11.302179) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184 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/e632fac588e56117c9204c5adcd4bd9ffa106e6cf64a9a6dc494da14511a2eaaa4c11eaedb17bc16949a70e9936e04605fde34a2da155696e531867a39086620", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a5272c0c18ccec82a5b37f37f1b0cba33639bb8819935d927f6f79d78ed5ca390229e6a4eeba34e66d91aaab098b705952e9f4e261739af9c3d07bc529d15d1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5697439e9b07cd297fbe0417e91599a6eb04b736b503c7267eb69e32a20249938d29c1dc43fd8c102ec5fdfb3f513c936d36503457d9630531fa887d915bb38", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1054671bbd10a896aa6d07bd9b7425c903c39177a086dcbfa0902720dcb22c67f09568d3c673c9dd9698f8e2543e8134da181ec21e0d4b931eedb2a1653e555a"} build_url:https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011427 build_url: 'https://queue.taskcluster.net/v1/task/dyqypZ5BQ3uJrBBpjoZhjw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e632fac588e56117c9204c5adcd4bd9ffa106e6cf64a9a6dc494da14511a2eaaa4c11eaedb17bc16949a70e9936e04605fde34a2da155696e531867a39086620", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2a5272c0c18ccec82a5b37f37f1b0cba33639bb8819935d927f6f79d78ed5ca390229e6a4eeba34e66d91aaab098b705952e9f4e261739af9c3d07bc529d15d1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5697439e9b07cd297fbe0417e91599a6eb04b736b503c7267eb69e32a20249938d29c1dc43fd8c102ec5fdfb3f513c936d36503457d9630531fa887d915bb38", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1054671bbd10a896aa6d07bd9b7425c903c39177a086dcbfa0902720dcb22c67f09568d3c673c9dd9698f8e2543e8134da181ec21e0d4b931eedb2a1653e555a"}' ========= master_lag: 0.22 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-14 02:52:11.530198) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 02:52:11.530561) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=438111f3d31ce799eff2119d000001bc-1505379848.513517-1136167184 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-14 02:52:11.641710) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 02:52:11.642043) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-14 02:52:11.694117) ========= ========= Total master_lag: 1.25 =========