builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-039 starttime: 1504281210.36 results: success (0) revision: cc85091ab81c7eb865d2cfb31539702c1bb7a18d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:30.355690) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:30.356145) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:30.356431) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:30.599390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:30.599803) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-01 08:53:30-- 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% 106M=0s 2017-09-01 08:53:30 (106 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138427 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:30.753852) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:30.754185) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015858 ========= master_lag: 0.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:30.900652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:30.906118) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cc85091ab81c7eb865d2cfb31539702c1bb7a18d --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 cc85091ab81c7eb865d2cfb31539702c1bb7a18d --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=5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-01 08:53:30,949 truncating revision to first 12 chars 2017-09-01 08:53:30,949 Setting DEBUG logging. 2017-09-01 08:53:30,949 attempt 1/10 2017-09-01 08:53:30,949 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cc85091ab81c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-01 08:53:31,531 unpacking tar archive at: mozilla-central-cc85091ab81c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.918085 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.842370) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.845685) ========= script_repo_revision: cc85091ab81c7eb865d2cfb31539702c1bb7a18d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.846099) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.846385) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-01 08:53:31.858542) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-01 08:53:31.858987) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:53:32 INFO - MultiFileLogger online at 20170901 08:53:32 in /builds/slave/test 08:53:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:53:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:53:32 INFO - {'append_to_log': False, 08:53:32 INFO - 'base_work_dir': '/builds/slave/test', 08:53:32 INFO - 'blob_upload_branch': 'Firefox', 08:53:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:53:32 INFO - 'branch': 'Firefox', 08:53:32 INFO - 'buildbot_json_path': 'buildprops.json', 08:53:32 INFO - 'code_coverage': False, 08:53:32 INFO - 'config_files': ('talos/linux_config.py',), 08:53:32 INFO - 'default_actions': ('clobber', 08:53:32 INFO - 'read-buildbot-config', 08:53:32 INFO - 'download-and-extract', 08:53:32 INFO - 'populate-webroot', 08:53:32 INFO - 'create-virtualenv', 08:53:32 INFO - 'install', 08:53:32 INFO - 'setup-mitmproxy', 08:53:32 INFO - 'run-tests'), 08:53:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:53:32 INFO - 'disable_ccov_upload': False, 08:53:32 INFO - 'disable_stylo': False, 08:53:32 INFO - 'download_minidump_stackwalk': True, 08:53:32 INFO - 'download_symbols': 'ondemand', 08:53:32 INFO - 'enable_stylo': False, 08:53:32 INFO - 'enable_webrender': False, 08:53:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 08:53:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:53:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:53:32 INFO - 'gecko_profile': False, 08:53:32 INFO - 'gecko_profile_interval': 0, 08:53:32 INFO - 'installer_path': 'installer.exe', 08:53:32 INFO - 'log_level': 'info', 08:53:32 INFO - 'log_name': 'talos', 08:53:32 INFO - 'log_to_console': True, 08:53:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:53:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:53:32 INFO - 'opt_config_files': (), 08:53:32 INFO - 'pip_index': False, 08:53:32 INFO - 'suite': 'dromaeojs-e10s', 08:53:32 INFO - 'system_bits': '32', 08:53:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:53:32 INFO - 'title': 'talos-linux64-ix-039', 08:53:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:53:32 INFO - 'use_talos_json': True, 08:53:32 INFO - 'verify': 'False', 08:53:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:53:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:53:32 INFO - 'work_dir': 'build'} 08:53:32 INFO - [mozharness: 2017-09-01 15:53:32.043788Z] Running clobber step. 08:53:32 INFO - Running pre-action listener: _resource_record_pre_action 08:53:32 INFO - Running main action method: clobber 08:53:32 INFO - rmtree: /builds/slave/test/build 08:53:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:53:33 INFO - Running post-action listener: _resource_record_post_action 08:53:33 INFO - [mozharness: 2017-09-01 15:53:33.569616Z] Finished clobber step (success) 08:53:33 INFO - [mozharness: 2017-09-01 15:53:33.569713Z] Running read-buildbot-config step. 08:53:33 INFO - Running pre-action listener: _resource_record_pre_action 08:53:33 INFO - Running main action method: read_buildbot_config 08:53:33 INFO - Using buildbot properties: 08:53:33 INFO - { 08:53:33 INFO - "project": "", 08:53:33 INFO - "product": "firefox", 08:53:33 INFO - "who": "archaeopteryx@coole-files.de", 08:53:33 INFO - "installer_path": "public/build/target.tar.bz2", 08:53:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 08:53:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 08:53:33 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 08:53:33 INFO - "stage_platform": "linux64-qr", 08:53:33 INFO - "basedir": "/builds/slave/test", 08:53:33 INFO - "buildnumber": 27, 08:53:33 INFO - "platform": "ubuntu64_hw_qr", 08:53:33 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:53:33 INFO - "slavebuilddir": "test", 08:53:33 INFO - "taskId": "E03IZP2aTfyQ2ocJSwVocQ", 08:53:33 INFO - "branch": "mozilla-central", 08:53:33 INFO - "script_repo_revision": "production", 08:53:33 INFO - "revision": "cc85091ab81c7eb865d2cfb31539702c1bb7a18d", 08:53:33 INFO - "slavename": "talos-linux64-ix-039", 08:53:33 INFO - "repo_path": "mozilla-central" 08:53:33 INFO - } 08:53:33 INFO - Finding installer, test and symbols from parent task. 08:53:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E03IZP2aTfyQ2ocJSwVocQ'}, attempt #1 08:53:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E03IZP2aTfyQ2ocJSwVocQ'}, attempt #1 08:53:34 INFO - Task dependencies: P0kX4SRdS6ifTGg50JASWg 08:53:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg'}, attempt #1 08:53:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 08:53:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json 08:53:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip 08:53:34 INFO - Running post-action listener: _resource_record_post_action 08:53:34 INFO - [mozharness: 2017-09-01 15:53:34.915648Z] Finished read-buildbot-config step (success) 08:53:34 INFO - [mozharness: 2017-09-01 15:53:34.915867Z] Running download-and-extract step. 08:53:34 INFO - Running pre-action listener: _resource_record_pre_action 08:53:34 INFO - Running main action method: download_and_extract 08:53:34 INFO - mkdir: /builds/slave/test/build/tests 08:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:53:34 INFO - trying https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json 08:53:34 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:53:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:53:35 INFO - Downloaded 989 bytes. 08:53:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:53:35 INFO - Using the following test package requirements: 08:53:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 08:53:35 INFO - u'common': [u'target.common.tests.zip'], 08:53:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:53:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:53:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:53:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:53:35 INFO - u'mozbase': [u'target.common.tests.zip'], 08:53:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:53:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:53:35 INFO - u'web-platform': [u'target.common.tests.zip', 08:53:35 INFO - u'target.web-platform.tests.tar.gz'], 08:53:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:53:35 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:53:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip 08:53:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:53:35 INFO - Fetch https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip into memory 08:53:36 INFO - Content-Length response header: 54957468 08:53:36 INFO - Bytes received: 54957468 08:53:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:53:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip 08:53:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:53:47 INFO - Fetch https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.common.tests.zip into memory 08:53:48 INFO - Content-Length response header: 54957468 08:53:48 INFO - Bytes received: 54957468 08:54:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.talos.tests.zip 08:54:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:54:00 INFO - Fetch https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.talos.tests.zip into memory 08:54:01 INFO - Content-Length response header: 14496650 08:54:01 INFO - Bytes received: 14496650 08:54:01 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')]} 08:54:01 INFO - trying https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 08:54:01 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:54:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:54:02 INFO - Downloaded 62657924 bytes. 08:54:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 08:54:02 INFO - mkdir: /builds/slave/test/properties 08:54:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:54:02 INFO - Writing to file /builds/slave/test/properties/build_url 08:54:02 INFO - Contents: 08:54:02 INFO - build_url:https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 08:54:02 INFO - Running post-action listener: _resource_record_post_action 08:54:02 INFO - Running post-action listener: find_tests_for_verification 08:54:02 INFO - Running post-action listener: set_extra_try_arguments 08:54:02 INFO - [mozharness: 2017-09-01 15:54:02.960743Z] Finished download-and-extract step (success) 08:54:02 INFO - [mozharness: 2017-09-01 15:54:02.960841Z] Running populate-webroot step. 08:54:02 INFO - Running pre-action listener: _resource_record_pre_action 08:54:02 INFO - Running main action method: populate_webroot 08:54:02 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:54:02 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:54:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:54:02 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:54:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:54:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:54:02 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:02 INFO - 'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:54:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:54:02 INFO - 'tests': ['damp', 'tps']}, 08:54:02 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:02 INFO - 'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['damp', 'tps']}, 08:54:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:54:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:54:02 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:54:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:54:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:54:02 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:02 INFO - 'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 08:54:02 INFO - 'other-e10s': {'tests': ['a11yr', 08:54:02 INFO - 'ts_paint', 08:54:02 INFO - 'tpaint', 08:54:02 INFO - 'sessionrestore', 08:54:02 INFO - 'sessionrestore_many_windows', 08:54:02 INFO - 'sessionrestore_no_auto_restore', 08:54:02 INFO - 'tabpaint']}, 08:54:02 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['a11yr', 08:54:02 INFO - 'ts_paint', 08:54:02 INFO - 'tpaint', 08:54:02 INFO - 'sessionrestore', 08:54:02 INFO - 'sessionrestore_many_windows', 08:54:02 INFO - 'sessionrestore_no_auto_restore', 08:54:02 INFO - 'tabpaint']}, 08:54:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 08:54:02 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 08:54:02 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['bloom_basic_singleton']}, 08:54:02 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['bloom_basic']}, 08:54:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:54:02 INFO - 'tsvgr_opacity', 08:54:02 INFO - 'tart', 08:54:02 INFO - 'tscrollx', 08:54:02 INFO - 'tsvg_static']}, 08:54:02 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['tsvgx', 08:54:02 INFO - 'tsvgr_opacity', 08:54:02 INFO - 'tart', 08:54:02 INFO - 'tscrollx', 08:54:02 INFO - 'tsvg_static']}, 08:54:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:54:02 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:02 INFO - 'talos_options': ['--enable-stylo'], 08:54:02 INFO - 'tests': ['tp5o']}, 08:54:02 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:54:02 INFO - 'talos_options': ['--mitmproxy', 08:54:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:54:02 INFO - '--firstNonBlankPaint'], 08:54:02 INFO - 'tests': ['tp6_google', 08:54:02 INFO - 'tp6_youtube', 08:54:02 INFO - 'tp6_amazon', 08:54:02 INFO - 'tp6_facebook']}, 08:54:02 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:54:02 INFO - 'talos_options': ['--enable-stylo', 08:54:02 INFO - '--mitmproxy', 08:54:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:54:02 INFO - '--firstNonBlankPaint'], 08:54:02 INFO - 'tests': ['tp6_google', 08:54:02 INFO - 'tp6_youtube', 08:54:02 INFO - 'tp6_amazon', 08:54:02 INFO - 'tp6_facebook']}, 08:54:02 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 08:54:02 INFO - 'talos_options': ['--enable-stylo', 08:54:02 INFO - '--stylo-threads=1', 08:54:02 INFO - '--mitmproxy', 08:54:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 08:54:02 INFO - '--firstNonBlankPaint'], 08:54:02 INFO - 'tests': ['tp6_google', 08:54:02 INFO - 'tp6_youtube', 08:54:02 INFO - 'tp6_amazon', 08:54:02 INFO - 'tp6_facebook']}, 08:54:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:54:02 INFO - 'talos_options': ['--xperf_path', 08:54:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:54:02 INFO - 'tests': ['tp5n']}, 08:54:02 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 08:54:02 INFO - 'talos_options': ['--enable-stylo', 08:54:02 INFO - '--xperf_path', 08:54:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:54:02 INFO - 'tests': ['tp5n']}}} 08:54:02 INFO - Running post-action listener: _resource_record_post_action 08:54:02 INFO - [mozharness: 2017-09-01 15:54:02.975192Z] Finished populate-webroot step (success) 08:54:02 INFO - [mozharness: 2017-09-01 15:54:02.975282Z] Running create-virtualenv step. 08:54:02 INFO - Running pre-action listener: _resource_record_pre_action 08:54:02 INFO - Running main action method: create_virtualenv 08:54:02 INFO - Creating virtualenv /builds/slave/test/build/venv 08:54:02 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:54:02 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 08:54:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 08:54:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:54:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:54:03 INFO - Installing distribute.............................................................................................................................................................................................done. 08:54:03 INFO - Installing pip...............done. 08:54:03 INFO - Return code: 0 08:54:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:54:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:54:03 INFO - Reading from file tmpfile_stdout 08:54:03 INFO - Output received: 08:54:03 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 08:54:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:03 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 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54:03 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 08:54:03 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 08:54:03 INFO - Using env: {'DISPLAY': ':0', 08:54:03 INFO - 'HOME': '/home/cltbld', 08:54:03 INFO - 'LANG': 'en_US.UTF-8', 08:54:03 INFO - 'LANGUAGE': 'en_US:en', 08:54:03 INFO - 'LOGNAME': 'cltbld', 08:54:03 INFO - 'MAIL': '/var/mail/cltbld', 08:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:03 INFO - 'MOZ_NO_REMOTE': '1', 08:54:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:03 INFO - 'NO_EM_RESTART': '1', 08:54:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:03 INFO - 'PWD': '/builds/slave/test', 08:54:03 INFO - 'SHELL': '/bin/bash', 08:54:03 INFO - 'SHLVL': '1', 08:54:03 INFO - 'TERM': 'linux', 08:54:03 INFO - 'TMOUT': '86400', 08:54:03 INFO - 'USER': 'cltbld', 08:54:03 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:03 INFO - '_': '/tools/buildbot/bin/python'} 08:54:05 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:54:05 INFO - Downloading/unpacking pip>=1.5 08:54:05 INFO - Running setup.py egg_info for package pip 08:54:05 INFO - warning: no files found matching 'pip/cacert.pem' 08:54:05 INFO - warning: no files found matching '*.html' under directory 'docs' 08:54:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:54:05 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:54:05 INFO - Installing collected packages: pip 08:54:05 INFO - Found existing installation: pip 1.1 08:54:05 INFO - Uninstalling pip: 08:54:05 INFO - Successfully uninstalled pip 08:54:05 INFO - Running setup.py install for pip 08:54:05 INFO - warning: no files found matching 'pip/cacert.pem' 08:54:05 INFO - warning: no files found matching '*.html' under directory 'docs' 08:54:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 08:54:05 INFO - no previously-included directories found matching 'docs/_build/_sources' 08:54:05 INFO - Installing pip script to /builds/slave/test/build/venv/bin 08:54:05 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 08:54:05 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 08:54:05 INFO - Successfully installed pip 08:54:05 INFO - Cleaning up... 08:54:05 INFO - Return code: 0 08:54:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:54: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')]} 08:54:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54: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')]} 08:54:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:05 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 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54:05 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 08:54:05 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 08:54:05 INFO - Using env: {'DISPLAY': ':0', 08:54:05 INFO - 'HOME': '/home/cltbld', 08:54:05 INFO - 'LANG': 'en_US.UTF-8', 08:54:05 INFO - 'LANGUAGE': 'en_US:en', 08:54:05 INFO - 'LOGNAME': 'cltbld', 08:54:05 INFO - 'MAIL': '/var/mail/cltbld', 08:54:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:05 INFO - 'MOZ_NO_REMOTE': '1', 08:54:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:05 INFO - 'NO_EM_RESTART': '1', 08:54:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:05 INFO - 'PWD': '/builds/slave/test', 08:54:05 INFO - 'SHELL': '/bin/bash', 08:54:05 INFO - 'SHLVL': '1', 08:54:05 INFO - 'TERM': 'linux', 08:54:05 INFO - 'TMOUT': '86400', 08:54:05 INFO - 'USER': 'cltbld', 08:54:05 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:05 INFO - '_': '/tools/buildbot/bin/python'} 08:54:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:05 INFO - Downloading/unpacking psutil>=3.1.1 08:54: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 08:54: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 08:54: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 08:54: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 08:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:54:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:54:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:54:06 INFO - Installing collected packages: psutil 08:54:06 INFO - Running setup.py install for psutil 08:54:06 INFO - building 'psutil._psutil_linux' extension 08:54:06 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 08:54:06 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 08:54:06 INFO - building 'psutil._psutil_posix' extension 08:54:06 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 08:54:06 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 08:54:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:54:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:54:07 INFO - Successfully installed psutil 08:54:07 INFO - Cleaning up... 08:54:07 INFO - Return code: 0 08:54:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:54: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')]} 08:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54: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')]} 08:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:54: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 mozsystemmonitor==0.3 08:54:07 INFO - Using env: {'DISPLAY': ':0', 08:54:07 INFO - 'HOME': '/home/cltbld', 08:54:07 INFO - 'LANG': 'en_US.UTF-8', 08:54:07 INFO - 'LANGUAGE': 'en_US:en', 08:54:07 INFO - 'LOGNAME': 'cltbld', 08:54:07 INFO - 'MAIL': '/var/mail/cltbld', 08:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:07 INFO - 'MOZ_NO_REMOTE': '1', 08:54:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:07 INFO - 'NO_EM_RESTART': '1', 08:54:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:07 INFO - 'PWD': '/builds/slave/test', 08:54:07 INFO - 'SHELL': '/bin/bash', 08:54:07 INFO - 'SHLVL': '1', 08:54:07 INFO - 'TERM': 'linux', 08:54:07 INFO - 'TMOUT': '86400', 08:54:07 INFO - 'USER': 'cltbld', 08:54:07 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:07 INFO - '_': '/tools/buildbot/bin/python'} 08:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:54:07 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 08:54:07 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 08:54:07 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 08:54:07 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 08:54:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:54:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:54:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:54:08 INFO - Installing collected packages: mozsystemmonitor 08:54:08 INFO - Running setup.py install for mozsystemmonitor 08:54:08 INFO - Successfully installed mozsystemmonitor 08:54:08 INFO - Cleaning up... 08:54:08 INFO - Return code: 0 08:54:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:54:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:54:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:54:08 INFO - Using env: {'DISPLAY': ':0', 08:54:08 INFO - 'HOME': '/home/cltbld', 08:54:08 INFO - 'LANG': 'en_US.UTF-8', 08:54:08 INFO - 'LANGUAGE': 'en_US:en', 08:54:08 INFO - 'LOGNAME': 'cltbld', 08:54:08 INFO - 'MAIL': '/var/mail/cltbld', 08:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:08 INFO - 'MOZ_NO_REMOTE': '1', 08:54:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:08 INFO - 'NO_EM_RESTART': '1', 08:54:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:08 INFO - 'PWD': '/builds/slave/test', 08:54:08 INFO - 'SHELL': '/bin/bash', 08:54:08 INFO - 'SHLVL': '1', 08:54:08 INFO - 'TERM': 'linux', 08:54:08 INFO - 'TMOUT': '86400', 08:54:08 INFO - 'USER': 'cltbld', 08:54:08 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:08 INFO - '_': '/tools/buildbot/bin/python'} 08:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:08 INFO - Downloading/unpacking jsonschema==2.5.1 08:54:08 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 08:54:08 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 08:54:08 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 08:54:08 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 08:54:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:54:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:54:09 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 08:54:09 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 08:54:09 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 08:54:09 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 08:54:10 INFO - Downloading functools32-3.2.3-2.tar.gz 08:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:54:10 INFO - warning: no files found matching '*.txt' 08:54:10 INFO - no previously-included directories found matching 'build' 08:54:10 INFO - no previously-included directories found matching 'dist' 08:54:10 INFO - no previously-included directories found matching '.git*' 08:54:10 INFO - Installing collected packages: jsonschema, functools32 08:54:10 INFO - Running setup.py install for functools32 08:54:10 INFO - warning: no files found matching '*.txt' 08:54:10 INFO - no previously-included directories found matching 'build' 08:54:10 INFO - no previously-included directories found matching 'dist' 08:54:10 INFO - no previously-included directories found matching '.git*' 08:54:10 INFO - Successfully installed jsonschema functools32 08:54:10 INFO - Cleaning up... 08:54:10 INFO - Return code: 0 08:54:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:54:10 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')]} 08:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:10 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')]} 08:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:10 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 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54:10 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 08:54:10 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 08:54:10 INFO - Using env: {'DISPLAY': ':0', 08:54:10 INFO - 'HOME': '/home/cltbld', 08:54:10 INFO - 'LANG': 'en_US.UTF-8', 08:54:10 INFO - 'LANGUAGE': 'en_US:en', 08:54:10 INFO - 'LOGNAME': 'cltbld', 08:54:10 INFO - 'MAIL': '/var/mail/cltbld', 08:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:10 INFO - 'MOZ_NO_REMOTE': '1', 08:54:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:10 INFO - 'NO_EM_RESTART': '1', 08:54:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:10 INFO - 'PWD': '/builds/slave/test', 08:54:10 INFO - 'SHELL': '/bin/bash', 08:54:10 INFO - 'SHLVL': '1', 08:54:10 INFO - 'TERM': 'linux', 08:54:10 INFO - 'TMOUT': '86400', 08:54:10 INFO - 'USER': 'cltbld', 08:54:10 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:10 INFO - '_': '/tools/buildbot/bin/python'} 08:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:54:10 INFO - Cleaning up... 08:54:10 INFO - Return code: 0 08:54:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:54:10 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')]} 08:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:10 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')]} 08:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:10 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 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54:10 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 08:54:10 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 08:54:10 INFO - Using env: {'DISPLAY': ':0', 08:54:10 INFO - 'HOME': '/home/cltbld', 08:54:10 INFO - 'LANG': 'en_US.UTF-8', 08:54:10 INFO - 'LANGUAGE': 'en_US:en', 08:54:10 INFO - 'LOGNAME': 'cltbld', 08:54:10 INFO - 'MAIL': '/var/mail/cltbld', 08:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:10 INFO - 'MOZ_NO_REMOTE': '1', 08:54:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:10 INFO - 'NO_EM_RESTART': '1', 08:54:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:10 INFO - 'PWD': '/builds/slave/test', 08:54:10 INFO - 'SHELL': '/bin/bash', 08:54:10 INFO - 'SHLVL': '1', 08:54:10 INFO - 'TERM': 'linux', 08:54:10 INFO - 'TMOUT': '86400', 08:54:10 INFO - 'USER': 'cltbld', 08:54:10 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:10 INFO - '_': '/tools/buildbot/bin/python'} 08:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:10 INFO - Downloading/unpacking blobuploader==1.2.4 08:54:10 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 08:54:10 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 08:54:10 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 08:54:10 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 08:54:11 INFO - Downloading blobuploader-1.2.4.tar.gz 08:54:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:54:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:54:11 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 08:54:11 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 08:54:11 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 08:54:11 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 08:54:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:54:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:54:12 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 08:54:12 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 08:54:12 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 08:54:12 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 08:54:12 INFO - Downloading docopt-0.6.1.tar.gz 08:54:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:54:12 INFO - Installing collected packages: blobuploader, requests, docopt 08:54:12 INFO - Running setup.py install for blobuploader 08:54:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:54:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:54:12 INFO - Running setup.py install for requests 08:54:12 INFO - Running setup.py install for docopt 08:54:12 INFO - Successfully installed blobuploader requests docopt 08:54:12 INFO - Cleaning up... 08:54:13 INFO - Return code: 0 08:54:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:13 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 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54:13 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 08:54:13 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 08:54:13 INFO - Using env: {'DISPLAY': ':0', 08:54:13 INFO - 'HOME': '/home/cltbld', 08:54:13 INFO - 'LANG': 'en_US.UTF-8', 08:54:13 INFO - 'LANGUAGE': 'en_US:en', 08:54:13 INFO - 'LOGNAME': 'cltbld', 08:54:13 INFO - 'MAIL': '/var/mail/cltbld', 08:54:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:13 INFO - 'MOZ_NO_REMOTE': '1', 08:54:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:13 INFO - 'NO_EM_RESTART': '1', 08:54:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:13 INFO - 'PWD': '/builds/slave/test', 08:54:13 INFO - 'SHELL': '/bin/bash', 08:54:13 INFO - 'SHLVL': '1', 08:54:13 INFO - 'TERM': 'linux', 08:54:13 INFO - 'TMOUT': '86400', 08:54:13 INFO - 'USER': 'cltbld', 08:54:13 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:13 INFO - '_': '/tools/buildbot/bin/python'} 08:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:54:13 INFO - Running setup.py (path:/tmp/pip-fJlPS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:54:13 INFO - Running setup.py (path:/tmp/pip-706Zga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:54:13 INFO - Running setup.py (path:/tmp/pip-1vtTwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:13 INFO - Running setup.py (path:/tmp/pip-2RIrE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:13 INFO - Running setup.py (path:/tmp/pip-pIVM1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:13 INFO - Running setup.py (path:/tmp/pip-BszaCQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:13 INFO - Running setup.py (path:/tmp/pip-c4tE8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:13 INFO - Running setup.py (path:/tmp/pip-mB3Rob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:13 INFO - Running setup.py (path:/tmp/pip-tLe3aD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:13 INFO - Running setup.py (path:/tmp/pip-SDZ4T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:13 INFO - Running setup.py (path:/tmp/pip-fCWRhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:14 INFO - Running setup.py (path:/tmp/pip-zynP11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:14 INFO - Running setup.py (path:/tmp/pip-fGA0yI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:14 INFO - Running setup.py (path:/tmp/pip-DDI6nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:14 INFO - Running setup.py (path:/tmp/pip-aRskpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:14 INFO - Running setup.py (path:/tmp/pip-hBHBh4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:14 INFO - Running setup.py (path:/tmp/pip-kjl95o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:54:14 INFO - Running setup.py install for manifestparser 08:54:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:54:14 INFO - Running setup.py install for mozcrash 08:54:14 INFO - Running setup.py install for mozdebug 08:54:14 INFO - Running setup.py install for mozdevice 08:54:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:54:14 INFO - Running setup.py install for mozfile 08:54:14 INFO - Running setup.py install for mozhttpd 08:54:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Running setup.py install for mozinfo 08:54:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Running setup.py install for mozInstall 08:54:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Running setup.py install for mozleak 08:54:15 INFO - Running setup.py install for mozlog 08:54:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Running setup.py install for moznetwork 08:54:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Running setup.py install for mozprocess 08:54:15 INFO - Running setup.py install for mozprofile 08:54:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Running setup.py install for mozrunner 08:54:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:54:15 INFO - Running setup.py install for mozscreenshot 08:54:15 INFO - Running setup.py install for moztest 08:54:16 INFO - Running setup.py install for mozversion 08:54:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:54:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:54:16 INFO - Cleaning up... 08:54:16 INFO - Return code: 0 08:54:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:16 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 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54:16 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 08:54:16 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 08:54:16 INFO - Using env: {'DISPLAY': ':0', 08:54:16 INFO - 'HOME': '/home/cltbld', 08:54:16 INFO - 'LANG': 'en_US.UTF-8', 08:54:16 INFO - 'LANGUAGE': 'en_US:en', 08:54:16 INFO - 'LOGNAME': 'cltbld', 08:54:16 INFO - 'MAIL': '/var/mail/cltbld', 08:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:16 INFO - 'MOZ_NO_REMOTE': '1', 08:54:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:16 INFO - 'NO_EM_RESTART': '1', 08:54:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:16 INFO - 'PWD': '/builds/slave/test', 08:54:16 INFO - 'SHELL': '/bin/bash', 08:54:16 INFO - 'SHLVL': '1', 08:54:16 INFO - 'TERM': 'linux', 08:54:16 INFO - 'TMOUT': '86400', 08:54:16 INFO - 'USER': 'cltbld', 08:54:16 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:16 INFO - '_': '/tools/buildbot/bin/python'} 08:54:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:54:16 INFO - Running setup.py (path:/tmp/pip-NR4Klk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:54:16 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)) 08:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:54:16 INFO - Running setup.py (path:/tmp/pip-Ewufws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:54:16 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)) 08:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:54:16 INFO - Running setup.py (path:/tmp/pip-mRRRlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:54:16 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)) 08:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:54:16 INFO - Running setup.py (path:/tmp/pip-fl7wyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:54:16 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)) 08:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:54:16 INFO - Running setup.py (path:/tmp/pip-Lb5CRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:54:16 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)) 08:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:54:16 INFO - Running setup.py (path:/tmp/pip-c6dN3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:54:16 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)) 08:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:54:16 INFO - Running setup.py (path:/tmp/pip-Deak_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:54:16 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)) 08:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:54:16 INFO - Running setup.py (path:/tmp/pip-lPsBe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:54:16 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)) 08:54:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:54:16 INFO - Running setup.py (path:/tmp/pip-wPAcY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:54:17 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)) 08:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:54:17 INFO - Running setup.py (path:/tmp/pip-PamWnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:54:17 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)) 08:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:54:17 INFO - Running setup.py (path:/tmp/pip-z2YLaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:54:17 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)) 08:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:54:17 INFO - Running setup.py (path:/tmp/pip-r8STLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:54:17 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)) 08:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:54:17 INFO - Running setup.py (path:/tmp/pip-ShcAlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:54:17 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)) 08:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:54:17 INFO - Running setup.py (path:/tmp/pip-PAK9sd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:54:17 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)) 08:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:17 INFO - Running setup.py (path:/tmp/pip-QWPZoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:54:17 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)) 08:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:54:17 INFO - Running setup.py (path:/tmp/pip-xeYako-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:54:17 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)) 08:54:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:54:17 INFO - Running setup.py (path:/tmp/pip-w0ttwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:54:17 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)) 08:54:17 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)) 08:54:17 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)) 08:54:17 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)) 08:54:17 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)) 08:54:17 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)) 08:54:17 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)) 08:54:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:18 INFO - Downloading blessings-1.6.tar.gz 08:54:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:54:18 INFO - Installing collected packages: blessings 08:54:18 INFO - Running setup.py install for blessings 08:54:18 INFO - Successfully installed blessings 08:54:18 INFO - Cleaning up... 08:54:18 INFO - Return code: 0 08:54:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:54:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:19 INFO - Reading from file tmpfile_stdout 08:54:19 INFO - Current package versions: 08:54:19 INFO - argparse == 1.2.1 08:54:19 INFO - blessings == 1.6 08:54:19 INFO - blobuploader == 1.2.4 08:54:19 INFO - docopt == 0.6.1 08:54:19 INFO - functools32 == 3.2.3-2 08:54:19 INFO - jsonschema == 2.5.1 08:54:19 INFO - manifestparser == 1.1 08:54:19 INFO - mozInstall == 1.13 08:54:19 INFO - mozcrash == 1.0 08:54:19 INFO - mozdebug == 0.1 08:54:19 INFO - mozdevice == 0.51 08:54:19 INFO - mozfile == 1.2 08:54:19 INFO - mozhttpd == 0.7 08:54:19 INFO - mozinfo == 0.10 08:54:19 INFO - mozleak == 0.1 08:54:19 INFO - mozlog == 3.5 08:54:19 INFO - moznetwork == 0.27 08:54:19 INFO - mozprocess == 0.25 08:54:19 INFO - mozprofile == 0.28 08:54:19 INFO - mozrunner == 6.13 08:54:19 INFO - mozscreenshot == 0.1 08:54:19 INFO - mozsystemmonitor == 0.3 08:54:19 INFO - moztest == 0.8 08:54:19 INFO - mozversion == 1.4 08:54:19 INFO - psutil == 3.1.1 08:54:19 INFO - requests == 1.2.3 08:54:19 INFO - wsgiref == 0.1.2 08:54:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:54:19 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')]} 08:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:19 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')]} 08:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:19 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 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54:19 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 08:54:19 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 08:54:19 INFO - Using env: {'DISPLAY': ':0', 08:54:19 INFO - 'HOME': '/home/cltbld', 08:54:19 INFO - 'LANG': 'en_US.UTF-8', 08:54:19 INFO - 'LANGUAGE': 'en_US:en', 08:54:19 INFO - 'LOGNAME': 'cltbld', 08:54:19 INFO - 'MAIL': '/var/mail/cltbld', 08:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:19 INFO - 'MOZ_NO_REMOTE': '1', 08:54:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:19 INFO - 'NO_EM_RESTART': '1', 08:54:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:19 INFO - 'PWD': '/builds/slave/test', 08:54:19 INFO - 'SHELL': '/bin/bash', 08:54:19 INFO - 'SHLVL': '1', 08:54:19 INFO - 'TERM': 'linux', 08:54:19 INFO - 'TMOUT': '86400', 08:54:19 INFO - 'USER': 'cltbld', 08:54:19 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:19 INFO - '_': '/tools/buildbot/bin/python'} 08:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 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)) 08:54:19 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 08:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:54:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:54:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 08:54:20 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)) 08:54:20 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)) 08:54:20 INFO - Installing collected packages: simplejson 08:54:20 INFO - Running setup.py install for simplejson 08:54:20 INFO - building 'simplejson._speedups' extension 08:54:20 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 08:54:21 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 08:54:21 INFO - Successfully installed simplejson 08:54:21 INFO - Cleaning up... 08:54:21 INFO - Return code: 0 08:54:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:54:21 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')]} 08:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:54:21 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')]} 08:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:54:21 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 0x15741f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x173da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18e0a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, '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': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', '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 08:54:21 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 08:54:21 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 08:54:21 INFO - Using env: {'DISPLAY': ':0', 08:54:21 INFO - 'HOME': '/home/cltbld', 08:54:21 INFO - 'LANG': 'en_US.UTF-8', 08:54:21 INFO - 'LANGUAGE': 'en_US:en', 08:54:21 INFO - 'LOGNAME': 'cltbld', 08:54:21 INFO - 'MAIL': '/var/mail/cltbld', 08:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:21 INFO - 'MOZ_NO_REMOTE': '1', 08:54:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:21 INFO - 'NO_EM_RESTART': '1', 08:54:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:21 INFO - 'PWD': '/builds/slave/test', 08:54:21 INFO - 'SHELL': '/bin/bash', 08:54:21 INFO - 'SHLVL': '1', 08:54:21 INFO - 'TERM': 'linux', 08:54:21 INFO - 'TMOUT': '86400', 08:54:21 INFO - 'USER': 'cltbld', 08:54:21 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:21 INFO - '_': '/tools/buildbot/bin/python'} 08:54:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:54:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:54:21 INFO - Cleaning up... 08:54:21 INFO - Return code: 0 08:54:21 INFO - Running post-action listener: _resource_record_post_action 08:54:21 INFO - Running post-action listener: _start_resource_monitoring 08:54:21 INFO - Starting resource monitoring. 08:54:21 INFO - [mozharness: 2017-09-01 15:54:21.820937Z] Finished create-virtualenv step (success) 08:54:21 INFO - [mozharness: 2017-09-01 15:54:21.821474Z] Running install step. 08:54:21 INFO - Running pre-action listener: _resource_record_pre_action 08:54:21 INFO - Running main action method: install 08:54:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:54:22 INFO - Reading from file tmpfile_stdout 08:54:22 INFO - Detecting whether we're running mozinstall >=1.0... 08:54:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:54:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:54:22 INFO - Reading from file tmpfile_stdout 08:54:22 INFO - Output received: 08:54:22 INFO - Usage: mozinstall [options] installer 08:54:22 INFO - Options: 08:54:22 INFO - -h, --help show this help message and exit 08:54:22 INFO - -d DEST, --destination=DEST 08:54:22 INFO - Directory to install application into. [default: 08:54:22 INFO - "/builds/slave/test"] 08:54:22 INFO - --app=APP Application being installed. [default: firefox] 08:54:22 INFO - mkdir: /builds/slave/test/build/application 08:54:22 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'] 08:54:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:54:38 INFO - Reading from file tmpfile_stdout 08:54:38 INFO - Output received: 08:54:38 INFO - /builds/slave/test/build/application/firefox/firefox 08:54:38 INFO - Running post-action listener: _resource_record_post_action 08:54:38 INFO - [mozharness: 2017-09-01 15:54:38.913215Z] Finished install step (success) 08:54:38 INFO - [mozharness: 2017-09-01 15:54:38.913480Z] Running setup-mitmproxy step. 08:54:38 INFO - Running pre-action listener: _resource_record_pre_action 08:54:38 INFO - Running main action method: setup_mitmproxy 08:54:38 INFO - Skipping: mitmproxy is not required 08:54:38 INFO - Running post-action listener: _resource_record_post_action 08:54:38 INFO - [mozharness: 2017-09-01 15:54:38.914616Z] Finished setup-mitmproxy step (success) 08:54:38 INFO - [mozharness: 2017-09-01 15:54:38.914836Z] Running run-tests step. 08:54:38 INFO - Running pre-action listener: _resource_record_pre_action 08:54:38 INFO - Running pre-action listener: _set_gcov_prefix 08:54:38 INFO - Running main action method: run_tests 08:54:38 WARNING - Try message not found. 08:54:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:54:38 INFO - Python 2.7.3 08:54:38 INFO - Return code: 0 08:54:38 INFO - grabbing minidump binary from tooltool 08:54:38 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')]} 08:54:38 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 0x18e4090>, '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 0x18e4d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18def90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:54:38 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 08:54:38 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 08:54:38 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 08:54:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:54:39 INFO - Return code: 0 08:54:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:54:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:54:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:54:39 INFO - ENV: RUST_BACKTRACE is now full 08:54:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:54:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:54:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 08:54:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 08:54:39 INFO - Using env: {'DISPLAY': ':0', 08:54:39 INFO - 'HOME': '/home/cltbld', 08:54:39 INFO - 'LANG': 'en_US.UTF-8', 08:54:39 INFO - 'LANGUAGE': 'en_US:en', 08:54:39 INFO - 'LOGNAME': 'cltbld', 08:54:39 INFO - 'MAIL': '/var/mail/cltbld', 08:54:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:54:39 INFO - 'MOZ_ACCELERATED': '1', 08:54:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:39 INFO - 'MOZ_NO_REMOTE': '1', 08:54:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:39 INFO - 'MOZ_WEBRENDER': '1', 08:54:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:39 INFO - 'NO_EM_RESTART': '1', 08:54:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:39 INFO - 'PWD': '/builds/slave/test', 08:54:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:39 INFO - 'RUST_BACKTRACE': 'full', 08:54:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:54:39 INFO - 'SHELL': '/bin/bash', 08:54:39 INFO - 'SHLVL': '1', 08:54:39 INFO - 'TERM': 'linux', 08:54:39 INFO - 'TMOUT': '86400', 08:54:39 INFO - 'USER': 'cltbld', 08:54:39 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:39 INFO - '_': '/tools/buildbot/bin/python'} 08:54:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 08:54:39 INFO - mozversion application_buildid: 20170901150340 08:54:39 INFO - mozversion application_changeset: cc85091ab81c7eb865d2cfb31539702c1bb7a18d 08:54:39 INFO - mozversion application_display_name: Nightly 08:54:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:54:39 INFO - mozversion application_name: Firefox 08:54:39 INFO - mozversion application_remotingname: firefox 08:54:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:54:39 INFO - mozversion application_vendor: Mozilla 08:54:39 INFO - mozversion application_version: 57.0a1 08:54:39 INFO - mozversion platform_buildid: 20170901150340 08:54:39 INFO - mozversion platform_changeset: cc85091ab81c7eb865d2cfb31539702c1bb7a18d 08:54:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:54:39 INFO - mozversion platform_version: 57.0a1 08:54:39 INFO - using testdate: 1504281279 08:54:39 INFO - actual date: 1504281279 08:54:39 INFO - starting webserver on 'localhost:33086' 08:54:39 INFO - SUITE-START | Running 2 tests 08:54:39 INFO - TEST-START | dromaeo_css 08:54:39 INFO - Initialising browser for dromaeo_css test... 08:54:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33086/getInfo.html -profile /tmp/tmpFeVMVh/profile 08:54:39 INFO - TEST-INFO | started process 5853 (/builds/slave/test/build/application/firefox/firefox http://localhost:33086/getInfo.html) 08:54:47 INFO - TEST-INFO | 5853: exit 0 08:54:47 INFO - Browser initialized. 08:54:47 INFO - Running cycle 1/1 for dromaeo_css test... 08:54:47 INFO - Using env: {'DISPLAY': ':0', 08:54:47 INFO - 'HOME': '/home/cltbld', 08:54:47 INFO - 'JSGC_DISABLE_POISONING': '1', 08:54:47 INFO - 'LANG': 'en_US.UTF-8', 08:54:47 INFO - 'LANGUAGE': 'en_US:en', 08:54:47 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 08:54:47 INFO - 'LOGNAME': 'cltbld', 08:54:47 INFO - 'MAIL': '/var/mail/cltbld', 08:54:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:54:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:54:47 INFO - 'MOZ_ACCELERATED': '1', 08:54:47 INFO - 'MOZ_CRASHREPORTER': '1', 08:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:54:47 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 08:54:47 INFO - 'MOZ_NO_REMOTE': '1', 08:54:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:54:47 INFO - 'MOZ_WEBRENDER': '1', 08:54:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:54:47 INFO - 'NO_EM_RESTART': '1', 08:54:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:54:47 INFO - 'PWD': '/builds/slave/test', 08:54:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:54:47 INFO - 'RUST_BACKTRACE': 'full', 08:54:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 08:54:47 INFO - 'SHELL': '/bin/bash', 08:54:47 INFO - 'SHLVL': '1', 08:54:47 INFO - 'TERM': 'linux', 08:54:47 INFO - 'TMOUT': '86400', 08:54:47 INFO - 'USER': 'cltbld', 08:54:47 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 08:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:54:47 INFO - '_': '/tools/buildbot/bin/python'} 08:54:47 INFO - TEST-INFO | started process 6048 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFeVMVh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 08:54:48 INFO - PID 6048 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 08:54:48 INFO - PID 6048 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 08:54:48 INFO - PID 6048 | 08:54:48 INFO - PID 6048 | (/builds/slave/test/build/application/firefox/firefox:6110): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 08:54:48 INFO - PID 6048 | 08:54:59 INFO - PID 6048 | RSS: Main: 205791232 08:54:59 INFO - PID 6048 | 08:55:03 INFO - PID 6048 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 08:57:57 INFO - PID 6048 | Cycle 1(1): loaded http://localhost:33086/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33086/tests/dromaeo/cssquery-ext.html) 08:57:59 INFO - PID 6048 | RSS: Main: 217059328 08:57:59 INFO - PID 6048 | 09:01:01 INFO - PID 6048 | Cycle 1(1): loaded http://localhost:33086/tests/dromaeo/cssquery-ext.html (next: http://localhost:33086/tests/dromaeo/cssquery-jquery.html) 09:01:02 INFO - PID 6048 | RSS: Main: 216973312 09:01:02 INFO - PID 6048 | 09:04:04 INFO - PID 6048 | Cycle 1(1): loaded http://localhost:33086/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33086/tests/dromaeo/cssquery-mootools.html) 09:04:04 INFO - PID 6048 | RSS: Main: 219832320 09:04:04 INFO - PID 6048 | 09:07:07 INFO - PID 6048 | Cycle 1(1): loaded http://localhost:33086/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33086/tests/dromaeo/cssquery-prototype.html) 09:07:07 INFO - PID 6048 | RSS: Main: 216879104 09:07:07 INFO - PID 6048 | 09:10:11 INFO - PID 6048 | Cycle 1(1): loaded http://localhost:33086/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33086/tests/dromaeo/cssquery-yui.html) 09:10:12 INFO - PID 6048 | RSS: Main: 218927104 09:10:12 INFO - PID 6048 | 09:13:14 INFO - PID 6048 | Cycle 1(1): loaded http://localhost:33086/tests/dromaeo/cssquery-yui.html (next: http://localhost:33086/tests/dromaeo/cssquery-dojo.html) 09:13:14 INFO - PID 6048 | RSS: Main: 221962240 09:13:14 INFO - PID 6048 | 09:13:14 INFO - PID 6048 | __start_tp_report 09:13:14 INFO - PID 6048 | _x_x_mozilla_page_load 09:13:14 INFO - PID 6048 | _x_x_mozilla_page_load_details 09:13:14 INFO - PID 6048 | |i|pagename|runs| 09:13:14 INFO - PID 6048 | |0;dojo.html;3271.6728327167284;3302.058913209735;3319.751018673597;3334.4164771165047;3354.983225083874;6309.211348581427;6350.587211831239;6352.936470635293;6389.201349831265;6446.419822215999;6079.179310793042;6130.44825965663;6160.106784516242;6200.821843849665;6217.720202590883;5835.095560188178;5854.063349864023;5863.8534036649;5871.266091738533;5962.761489540413;5950;5988.3712210217955;5991.640501569898;5999.730012149464;6038.456538911497;1052.1988061883294;5762.683052432115;5929.555283353744;5977;6020.729067191987;5835.854103647401;5856.326522449887;5869.7945571904775;5965.731542080617;5974.133750606181;5842.503387212092;5863.706814659293;5933.881322373529;5955.910661340084;5956.791512297049;6249.78125765596;6294.0873573332065;6352.07894855248;6394.6163230205875;6449.83875403114;6805.047293378931;6846.4522838172825;6881;6898.0687607172895;6921.169459664822;6830.487713421513;6850.177978642545;6872.62200578968;6937.132858392701;6943.236244013155;6270.937290627081;6280.371962803729;6295.96852015737;6346.904796428011;6411.807645770541;7070.505064645528;7123.786286411416;7126.964365178244;7134.393576545948;7141.285871412921;6714.630695311707;6729.293424190488;6751.459883209332;6815.829604259834;6821.283765204582;6612.107365505595;6742.056112144303;6789.287124851919;6904.206016308189;6961.129858767654;6571.769988050395;6630.30381809903;6642.800715978528;6649.368310010541;6692.866142677217;6598.274189839114;6614.470842332602;6677.933220667828;6689.297623749533;6698.96650516754;5286.5506431952945;5305.628605997543;5311.150215965427;5312.681239125664;5315.069862773999;4523.932141017887;4681.765911704401;4685.086408150446;4737.076270127292;4791.904161916742;6000.33996260411;6003.699815009232;6076.3316035236085;6077.24034495688;6086.726097325631;6702.329767023259;6848.554843935129;6865.897011544831;6947.5831450113155;6952.374286314256;5921.73352199152;5931.406859314034;5945.583809133319;5945.613535120204;5963.970180149072;5859.736311865976;5869.266341707286;5884.058550631964;5886.470217680429;5915.940840591625;6618.271990081087;6644.269130395653;6649.135612370361;6697.196336439659;6721.529492935448;6648.700808463631;6710.66446677674;6710.698018589273;6719.966400167968;6724.4284235840505;6615.37153970372;6624.436922861611;6629.370209830058;6630.602163870183;6643.50173736962;6508.902366464602;6735.797926062226;6743.5953842769595;6745.089412929192;6767.627780472121;6824.317568243136;6852.897206542006;6899.137607799025;6951;6979.790606281819;6476;6569.671516424255;6618.801435956929;6709.832254193684;6725;6582.1443212384;6596.70314835824;6604.405603495708;6640.269570347161;6656.134702488645;6584.209894812653;6611.173603299588;6625.204975402789;6629.602223866584;6631.3700198481065;6651.102101216274;6659.101021362054;6680.565763225375;6720.596764194164;6767.89848152288;6457.096006558992;6471.158749362553;6516.6415847128865;6540.149780528501;6555.967220163869 09:13:14 INFO - PID 6048 | |1;ext.html;767.4244316762428;829.7344849648108;856.4689892266795;882.5454890731271;887.9023307436181;39300;40223.59776402235;40348;40576.59423405765;40791.592084079224;37064.629353706456;37199.256014879684;37297.81351093247;37393.06517337061;37466.063348416305;6918.238993710688;6971.3028697130385;7029.261927497616;7043.577385356869;7046.224915259343;6906.827329316757;6939.167299924016;6981.301869813004;6982.860342793141;6983.2318444971015;6937;7554.169041405441;7720.227977202291;7727.690892364299;7730.9226907731045;7566.621668916532;7613.733519326853;7625.31372176504;7674.424418168632;7675.577843218621;7473.476856620039;7480.513766605153;7646.044244469442;7665.386769058462;7666.9616651916385;36673.44989825155;36694;37008;37107.62892371069;37111.628883711084;3523.1016090896915;3523.4890940813693;3538.6815186633326;3567.1973805893726;3570.3751843427294;31186.532202016988;31428.842855785802;31548;31565.21086972821;31595.84202078998;4980.800767969277;5131.615128865313;5149.665271757362;5164.277001219794;5216.061109000379;182145;184332;185453.07273463547;185477;185584;48189.27716084261;48195.277070843964;48528.5147148524;48616.51383486191;48724.756376218604;50562.494375056514;51221.743891280305;52480;52649.2102618461;52835;14794.926025369805;14938.850611493746;15061.849381506263;15107.848921510864;15129;12332.445039973223;12341.50633974631;12353.135280530456;12377.62867113988;12380.690482737824;13471.528496502575;13761.105528140639;13768.518101866586;13826.585202443943;13880.444782208599;5361.249425080492;5437.619366644297;5542.865996591261;5546.084895992197;5564.582656300794;991.4299277915084;1000.7598176437602;1012.9392236465688;1018;1033.571068006784;23003.079876805074;23081.192158274378;23173.073077077064;23439.765602344098;23521.76478235196;1086.7989421956963;1110.9055730262858;1113.9777204455866;1115.5537784885948;1131.4399372310759;12477.251364918135;12549.372531373394;16020.439284625216;16061.196940153179;16638.83361166397;231443;232716;233667;234281;234669;47149.292760608616;48375.5162448371;48452.27321590179;55699;56580.434195657515;1256.3592567790492;1261.703499677575;1264.2730430002784;1267.480333063421;1373.4300265389952;26480;26587.93648254048;26802.59796103099;27010.86494567593;27329.31676708098;37007;37175.81412092922;37201.25597488089;37333;37502.62497374992;208648;209146;209888;210946;211260;31273.374532508766;32093.234872081324;32320;32542.674573253964;33068;40072.3989140169;40701.592984070965;40835.38746918739;40951.7952410236;41143.38284925669;30372.84813575918;30642.233944151576;30942;31165.53251701181;31331.686683132877;30236.546451802802;30462.238444039078;30730.53904191394;30991.535126972663;31492;33929.83035084809;34257.14357141092;34313.828430857684;36430.4535431974;36998.44502332521;31897;32384.83807580947;32487;32772.67227327696;32817;24702.382440439134;24782.628260575744;24790.628140577544;25041.74958250467;25568.48863022692 09:13:14 INFO - PID 6048 | |2;jquery.html;569.7464628240433;572.2960758267332;572.3246569048526;572.6392372805133;579.336659524844;21902.561948761017;22828.54342913141;22873.656895146567;22881.42796430086;22919.312420627368;23561.293161205147;26226.606600900956;26313;26328.47343053138;26427;6719.092922455455;6827;6867.244603093655;6870.416014638762;6881.724731010779;6413.454856337217;6734.797956061326;6764.458843292549;7001.509894307401;7032.0506731591095;7014.473914456411;7050.647467626623;7051.7884463465925;7061.823454413629;7064.540804847694;6976.686049127776;7054.788356349318;7062.788116356492;7063.470239732041;7067.257937916522;6826.897596536056;6839.863202735967;6855.485838562127;6910.239873613898;6915.792526224222;30238;30616.38767224643;30891;31806.681933180604;32019.679803201903;2078.116800359841;2103.4846462616606;2105.7473103227558;2116.1958455786857;2119.8304135669264;20926.162953481842;21324.360269191948;21506.139754409804;21557.676634850333;21605.027773750062;2518.55925213087;2533.987330063338;2633.670791151106;2637.6966648835437;2657.800664950117;136527.999999999;144184;144350;144504.277478613;144851.27574362166;19282.517937051685;21209.36371908814;21506.89246553757;21795.44216224884;21834.781652183276;43259.134817304104;43600.12799743987;43638.34542481865;44045.55954440415;44312.56280904581;9430;9816.65641702537;9974.601015959426;9974.700758977096;10121.24090693201;8985.101489850962;9007.684731034513;9176.6788162414;9233.261339092856;9303.441793492411;7731.729389471341;8987.236084932854;9155.587998540082;9278.164965153033;9290.349675522784;2779.360747028195;2800.523910935146;2825.1948194764395;2832.3344014156655;2951.985240073786;958.5494817435793;976.4043933200742;987.5605355616825;988.8566157907208;1000.3547711726042;19683;19828.60342793165;19986.60026799456;20021.29925452631;20416.489587760425;1037.6575730009079;1045.6131231444415;1054.4095306628312;1056.4612047855649;1059.5179193466954;10592.788144237073;11005.229633925548;11101.888981110085;11172.497237624326;11235.325880447199;469504;489582;490985;492866;499441;114824;116752;118226;118968.40515797366;120124.3993780043;1190.0776897904193;1202.3146806320312;1208.353530860985;1216.1393068605048;1274.1781550899802;20464.283750069273;20792.168313267906;20929.790702092785;20993.475163121046;21214.575708486052;53769;55784;56532.999999998356;56764;57529.71235143798;68711;71732.28267717257;72312.63843680748;72459;73431.63284183545;12016.158868879444;12466.127371083938;14018.859811401755;14489.637759056108;18526.814731852508;23183.304500865;23191.88404057969;23384.363094583216;23403.531929360975;23411.76588234096;18220;18632.906835465736;18709.43871683852;18879.339223127125;18948.526286842938;16227;16615.50153495397;16779.328826846788;17203.483895483154;17476.126193690518;18473.353432629792;18748.81251187471;19738.40784776459;19771;19935.700964485255;20174;20674.276400325918;20757.584848303253;20847.64490308125;20887.8955605221;17922.19350129221;18797.624047519246;18817;18844.811551884854;18853.528661783566 09:13:14 INFO - PID 6048 | |3;mootools.html;1315.2371624457815;1348.732191739764;1360.1770928588207;1361.8706222908827;1372.5196181336532;14709.41162353506;15081.622959425993;15375.077495350286;15385.846141538581;15405.152716600582;15374.38502459903;15764.527064188063;15811;15812.762808557853;15862.36550537977;2795.6365672462653;2811.578263260507;2835.248659105339;2836.602875597418;2837.1914004508717;2675.652165218519;2742.4103817679197;2759.296379423244;2774.0706863200853;2774.3480282133783;2748.5464898291757;2777.8611069446547;2778.8610569471443;2779.0967935420967;2780.402213524092;2609.7390260973843;2664.373872140055;2666.3467450474564;2679.0489376271435;2679.9598006029923;2592.3519120219944;2594.4292255703713;2600.1029644772525;2630.9605355919675;2632.3813903732607;14563.126212427289;14815.333310001075;14815.851841481555;14834.925825370912;14871.702565948728;7916.6833326666865;8173.346132309401;8190.303824174952;8194.344452443791;8212.301954333889;5313.521783039507;5524.58565607581;5627.6904770237625;5705.686187259699;5711.228984087136;1866.122922226565;1914.731937528747;1926.3257859748976;1978.3174804692358;1978.3273686946507;26116.085936992306;26235;26365.3408664783;26476.07333743329;26498.07256746024;6442.259140198843;6673.733050678015;6690.59856408617;6839.31606839322;6880.2431732509385;16748.66502669964;16802.663946720997;16824.74762878558;16833.41083062087;16846.747298790528;7757.146713861471;7871.409644276588;7935.365170786324;7951.721689740832;7967.9203207967175;6018.879622407528;6278.717457714414;6446.871062578723;6474.935250647433;7259.092613423322;5214.13966695491;5359.22291267764;5409.83770486886;5445.455454454602;5497.120460726264;1537.2467490929587;1540.414642435867;1566.0838409530552;1574.3702518992495;1582.7071991681346;883.5228976352826;902.228594551662;913.4564933864473;921.8755468011867;924.7872989212519;7722.073351197892;8207.753767386988;8255.711050113337;8410.747677569683;8424.368172386972;710.4458522352573;711.9430445564342;714.3784907130726;717.476242343082;732.7032551816523;4540.081104942552;4563.338315944169;4579.015511665008;4652.464966528825;4688.9531104689195;21422.678659820114;21946.89026554857;22057.669134962667;22063.33809985703;22223.11107555712;21012.684809727867;21084.47288817792;21564.784352156592;21615.783842161378;21893.233736819137;10242.078212960869;10350.223733219887;10402.219833512518;10439.321444106108;10456.32033917793;8460.534670593177;8518.574071296534;8528.147185281423;8659.393842430969;8733.606987685696;17898.5525361867;18219.45341639753;18469.53826154357;18625.906870466104;18861.05694715286;18598.349057782914;18996.335128270443;19222.61554768869;19283;19409.685453921466;10668.89331106679;10885.891141088488;10901.727456813644;10914.672559823219;10927.508262128064;13046.869531304565;13538.323083845966;13555.051146419655;13615;13634.250116243702;11052.723681907695;11160.107191424504;11381.317120972437;11571.074314055024;11645.534178632759;10994.230403871652;11130.944345278222;11385.430728463709;11477.942610286895;11711.414429278673;9539.761505962406;9569.569369378534;9577.521123943914;9603.471809050568;9622.326437149612;6790.966045169743;6797.1843378794865;6884.4148247399535;6912.617476504619;6972;6956.478264130312;7060.082189315356;7099.609521476162;7189.065421495172;7190.4966652335925 09:13:14 INFO - PID 6048 | |4;prototype.html;500.24213316825006;510.12004292595253;526.6260954722147;534.3854567247662;540.9675419474831;6345.650989195592;6361.363863613637;6399.27219650694;6467.214261446525;6495.447886929611;6019.729112189951;6300.8729282320155;6324.462420694235;6330.683465826702;6340.873182536347;5041.773120209581;5059.468755780646;5067.873303167414;5077.974610126954;5089.338386009814;4900.460949295574;5032.295478632995;5044.268581055745;5060.797568097271;5088.974555127219;5031.371078615173;5036.949630503685;5047.242913562958;5066.949330506703;5076.111680455924;4976.950230497685;4990.47600002;4997.750112494379;5014.323066386028;5052.427553064483;4448.421705178338;4511.661625378109;4517.051419201972;4535.818567257305;4540.296254080623;6185.5979361341815;6216.782412615582;6246.437820596123;6282.654454005028;6291.269900777323;5778;5827.592068555203;5834.824955251349;5834.97082514589;5840.3575606683235;6574.211094668671;6599.373059559334;6687.1975362956755;6723.5965842049145;6734.420593996081;4891.926621100651;4895.04198320671;4901.901961960741;4906.264060390934;4929.605631549503;6985.790426287169;7029.753958611424;7060.894086588705;7083.433325333961;7092.609906455143;5043.4956504349275;5065.164247899129;5120.462351453119;5128.4101928402315;5149.227615857576;5127.923191107533;5141.408737995179;5160.380754309432;5196.246544251062;5215.947840521622;5686.289213848269;5703.942960570341;5738.913916291259;5760.942390576124;5801.9129713054335;6674.933250667528;6715.529912906148;6722;6757;6768.2216545097945;1682.2682133272067;1723.2073246306588;1736.5919009032866;1740.4604572582539;1755.5698853780905;1185.6680129563736;1191.362620997762;1235.5428491458217;1256.8491780986158;1268.7843066678686;5827.679477628772;5841.649501029951;5851.6781577012825;5886.205362276123;5907.527397808165;6603.438707709898;6628.734850605922;6659.9031450375805;6821.3519715626935;6882.827929301708;5907.379725128885;5949.553783466257;5982.850428739229;5999.160117583542;6043.398499397663;3605.5853576838476;3626.1297288650535;3637.381645120336;3643.8339731285732;3650.5071815304595;6706.798796036127;6713.1272934519175;6761.695723692445;6788.389044985975;6818.147731533559;6873.20958089816;6901.896471552931;6955.739384699993;7000;7072.752453664097;6687.230968438692;6728.947670960404;6758.695858686371;6782.321767823277;6825.317468253234;6863.142107236596;6905.343992320722;6948.756793512202;6962;6968.965155174192;6762.628055456997;6865.862682746417;6866.081915186126;6886;6895.862082758216;6583.539152259296;6654.367835055662;6716.899246511209;6758.594484330956;6763.222229443677;4957.553820156203;4993.575546078624;4999.175136102503;5033.773480193473;5078.619103567174;6642.667866606747;6831.521793474409;6877.415419689382;6970.512064155663;7003.614801185984;6526.934730652632;6543.476521878143;6599.4390476808085;6607.3062328456745;6676.866462670817;6506.707198175999;6571.5399922005;6624.8012559623285;6661.700223490052;6668.366505182;6447.806565803034;6490.578112422678;6530.346965303622;6560.081588577698;6580.80257592273;6438.6136831790245;6553.4101930826455;6591.604503729792;6592.373724496165;6652.800415987528;6347.873042539216;6446.194225721785;6504.902426463512;6548.705308261045;6571.211454625475 09:13:14 INFO - PID 6048 | |5;yui.html;867.6486023160618;895.247991686983;897.9865302020471;905.1355955062916;907.0793144957867;18014.639707205864;19347.516312092186;19404.805951940478;19420.223191072375;19500.804991950077;14865.331060102295;14925.328360223788;16679.08265045422;20755.06602202901;20823.791762082375;966.3332300712503;968.1867231525517;974.0843607009424;976.077606661705;976.9560369783377;989.7723523589578;995.706266651336;996.4370130876068;997.5760301871712;998.3610489286863;736.532301988236;759.0360242492031;997.9850302245472;1042.0361165921531;1051.1695760349314;1026.5226669598599;1027.1577306608583;1036.7874585709912;1040.5057597641135;1040.5473618975732;753.427395179662;757.3448966643869;764.109812068939;766.4596459496098;767.8425922685836;18721.62556748871;18939.43181704551;19979.50051248716;20229;20299.797002030085;21207.151713931424;21304.786952130435;21808.89095554528;21925.122995080335;22121;17168.828311716847;17365.479035628825;17458.91270543652;17615.119244037876;17658.91170544152;3708.6662200402093;3709.2025214578725;3710.7216958728204;3714.461403096536;3726.142987112952;129859;132174.3391283047;133548;133933;135231.32384338015;16420.507384778477;16440.260188291555;16605.91697041507;16668.58328541796;16835.915820421065;28007;28945.71054289472;29318;30218.09345719632;31299.37401252008;8173.632186551619;8211.671533138608;8220.63007164679;8274.462159959583;8288.502689838508;4054.655354294859;4107.774072425986;4227.196832601796;4242.490901091827;4267.061246525759;5252.684838909678;5364.946350536523;5373.543248823894;5377.784888604412;5393.1370980642905;975.7755716185317;986.3539381704994;995.7560397702464;1006.6174853555748;1008.253892119826;687.6183718036538;690.1132045321738;703.6868593475854;711.4379640084379;713.8001359619315;11409.201355905006;11474.196806223652;11654.592089276835;12869.806952895715;13109.934450327688;684.8767221900106;689.8827199376118;695.6695569604398;703.8979347994513;715.8425146467769;5508.008558459515;5609.719514024282;5618.157276408571;5642.6614403135945;5730.656160630376;105163.47418263013;106352;106550.46724766327;107736.46131769291;108642.45678771714;93694.53152734193;94542;96744;97377.51311243398;97429.51285243529;85543.14456855477;86523;86604.13395866087;86945.56527217323;90186.54906725425;10291.897081029094;10464.581416743245;10512.335853561663;10596.788064238517;10719.732006699895;41260.38109428301;41423.1715365697;41530.1693966125;42112.57887421209;42365.576344236164;75683.62158189174;75853.24146758462;75991.62004190165;77411.61294193719;78201.60899195468;18900.716489252947;18934.52663683419;18972.71540926915;19314;19336.613267734847;20400.79599203989;20943.790562094186;20945.476363091046;20975;21602.13591456324;16804.495865124067;16835.326586936386;17062.914685426495;19100.23599056022;19789.703154452407;17708.822911770716;17836.948750893815;18817;19218;19556.706649399985;17302.65394692074;17508.299668013136;17914.46256612304;18143.909280453514;19405.902970485622;12986.870131298567;13164.275964822027;13625.833766026139;14172.00795944274;14372;13482;13722.382492787649;13740.725185496434;13766.93116534411;13879.514217002357 09:13:14 INFO - PID 6048 | __end_tp_report 09:13:14 INFO - PID 6048 | __start_cc_report 09:13:14 INFO - PID 6048 | _x_x_mozilla_cycle_collect,346 09:13:14 INFO - PID 6048 | __end_cc_report 09:13:14 INFO - PID 6048 | __startTimestamp1504282394304__endTimestamp 09:13:14 INFO - PID 6048 | 09:13:14 INFO - PID 6048 | ------- Summary: start ------- 09:13:14 INFO - PID 6048 | Number of tests: 6 09:13:14 INFO - PID 6048 | 09:13:14 INFO - PID 6048 | [#0] dojo.html Cycles:165 Average:6250.43 Median:6569.67 stddev:835.83 (12.7%) stddev-sans-first:805.07 09:13:14 INFO - PID 6048 | Values: 3271.7 3302.1 3319.8 3334.4 3355.0 6309.2 6350.6 6352.9 6389.2 6446.4 6079.2 6130.4 6160.1 6200.8 6217.7 5835.1 5854.1 5863.9 5871.3 5962.8 5950.0 5988.4 5991.6 5999.7 6038.5 1052.2 5762.7 5929.6 5977.0 6020.7 5835.9 5856.3 5869.8 5965.7 5974.1 5842.5 5863.7 5933.9 5955.9 5956.8 6249.8 6294.1 6352.1 6394.6 6449.8 6805.0 6846.5 6881.0 6898.1 6921.2 6830.5 6850.2 6872.6 6937.1 6943.2 6270.9 6280.4 6296.0 6346.9 6411.8 7070.5 7123.8 7127.0 7134.4 7141.3 6714.6 6729.3 6751.5 6815.8 6821.3 6612.1 6742.1 6789.3 6904.2 6961.1 6571.8 6630.3 6642.8 6649.4 6692.9 6598.3 6614.5 6677.9 6689.3 6699.0 5286.6 5305.6 5311.2 5312.7 5315.1 4523.9 4681.8 4685.1 4737.1 4791.9 6000.3 6003.7 6076.3 6077.2 6086.7 6702.3 6848.6 6865.9 6947.6 6952.4 5921.7 5931.4 5945.6 5945.6 5964.0 5859.7 5869.3 5884.1 5886.5 5915.9 6618.3 6644.3 6649.1 6697.2 6721.5 6648.7 6710.7 6710.7 6720.0 6724.4 6615.4 6624.4 6629.4 6630.6 6643.5 6508.9 6735.8 6743.6 6745.1 6767.6 6824.3 6852.9 6899.1 6951.0 6979.8 6476.0 6569.7 6618.8 6709.8 6725.0 6582.1 6596.7 6604.4 6640.3 6656.1 6584.2 6611.2 6625.2 6629.6 6631.4 6651.1 6659.1 6680.6 6720.6 6767.9 6457.1 6471.2 6516.6 6540.1 6556.0 09:13:14 INFO - PID 6048 | 09:13:14 INFO - PID 6048 | [#1] ext.html Cycles:180 Average:37531.11 Median:34285.49 stddev:54483.59 (158.9%) stddev-sans-first:54566.50 09:13:14 INFO - PID 6048 | Values: 767.4 829.7 856.5 882.5 887.9 39300.0 40223.6 40348.0 40576.6 40791.6 37064.6 37199.3 37297.8 37393.1 37466.1 6918.2 6971.3 7029.3 7043.6 7046.2 6906.8 6939.2 6981.3 6982.9 6983.2 6937.0 7554.2 7720.2 7727.7 7730.9 7566.6 7613.7 7625.3 7674.4 7675.6 7473.5 7480.5 7646.0 7665.4 7667.0 36673.4 36694.0 37008.0 37107.6 37111.6 3523.1 3523.5 3538.7 3567.2 3570.4 31186.5 31428.8 31548.0 31565.2 31595.8 4980.8 5131.6 5149.7 5164.3 5216.1 182145.0 184332.0 185453.1 185477.0 185584.0 48189.3 48195.3 48528.5 48616.5 48724.8 50562.5 51221.7 52480.0 52649.2 52835.0 14794.9 14938.9 15061.8 15107.8 15129.0 12332.4 12341.5 12353.1 12377.6 12380.7 13471.5 13761.1 13768.5 13826.6 13880.4 5361.2 5437.6 5542.9 5546.1 5564.6 991.4 1000.8 1012.9 1018.0 1033.6 23003.1 23081.2 23173.1 23439.8 23521.8 1086.8 1110.9 1114.0 1115.6 1131.4 12477.3 12549.4 16020.4 16061.2 16638.8 231443.0 232716.0 233667.0 234281.0 234669.0 47149.3 48375.5 48452.3 55699.0 56580.4 1256.4 1261.7 1264.3 1267.5 1373.4 26480.0 26587.9 26802.6 27010.9 27329.3 37007.0 37175.8 37201.3 37333.0 37502.6 208648.0 209146.0 209888.0 210946.0 211260.0 31273.4 32093.2 32320.0 32542.7 33068.0 40072.4 40701.6 40835.4 40951.8 41143.4 30372.8 30642.2 30942.0 31165.5 31331.7 30236.5 30462.2 30730.5 30991.5 31492.0 33929.8 34257.1 34313.8 36430.5 36998.4 31897.0 32384.8 32487.0 32772.7 32817.0 24702.4 24782.6 24790.6 25041.7 25568.5 09:13:14 INFO - PID 6048 | 09:13:14 INFO - PID 6048 | [#2] jquery.html Cycles:180 Average:36120.18 Median:23407.65 stddev:82442.08 (352.2%) stddev-sans-first:82630.15 09:13:14 INFO - PID 6048 | Values: 569.7 572.3 572.3 572.6 579.3 21902.6 22828.5 22873.7 22881.4 22919.3 23561.3 26226.6 26313.0 26328.5 26427.0 6719.1 6827.0 6867.2 6870.4 6881.7 6413.5 6734.8 6764.5 7001.5 7032.1 7014.5 7050.6 7051.8 7061.8 7064.5 6976.7 7054.8 7062.8 7063.5 7067.3 6826.9 6839.9 6855.5 6910.2 6915.8 30238.0 30616.4 30891.0 31806.7 32019.7 2078.1 2103.5 2105.7 2116.2 2119.8 20926.2 21324.4 21506.1 21557.7 21605.0 2518.6 2534.0 2633.7 2637.7 2657.8 136528.0 144184.0 144350.0 144504.3 144851.3 19282.5 21209.4 21506.9 21795.4 21834.8 43259.1 43600.1 43638.3 44045.6 44312.6 9430.0 9816.7 9974.6 9974.7 10121.2 8985.1 9007.7 9176.7 9233.3 9303.4 7731.7 8987.2 9155.6 9278.2 9290.3 2779.4 2800.5 2825.2 2832.3 2952.0 958.5 976.4 987.6 988.9 1000.4 19683.0 19828.6 19986.6 20021.3 20416.5 1037.7 1045.6 1054.4 1056.5 1059.5 10592.8 11005.2 11101.9 11172.5 11235.3 469504.0 489582.0 490985.0 492866.0 499441.0 114824.0 116752.0 118226.0 118968.4 120124.4 1190.1 1202.3 1208.4 1216.1 1274.2 20464.3 20792.2 20929.8 20993.5 21214.6 53769.0 55784.0 56533.0 56764.0 57529.7 68711.0 71732.3 72312.6 72459.0 73431.6 12016.2 12466.1 14018.9 14489.6 18526.8 23183.3 23191.9 23384.4 23403.5 23411.8 18220.0 18632.9 18709.4 18879.3 18948.5 16227.0 16615.5 16779.3 17203.5 17476.1 18473.4 18748.8 19738.4 19771.0 19935.7 20174.0 20674.3 20757.6 20847.6 20887.9 17922.2 18797.6 18817.0 18844.8 18853.5 09:13:14 INFO - PID 6048 | 09:13:14 INFO - PID 6048 | [#3] mootools.html Cycles:180 Average:9232.75 Median:14581.22 stddev:6661.82 (45.7%) stddev-sans-first:6653.95 09:13:14 INFO - PID 6048 | Values: 1315.2 1348.7 1360.2 1361.9 1372.5 14709.4 15081.6 15375.1 15385.8 15405.2 15374.4 15764.5 15811.0 15812.8 15862.4 2795.6 2811.6 2835.2 2836.6 2837.2 2675.7 2742.4 2759.3 2774.1 2774.3 2748.5 2777.9 2778.9 2779.1 2780.4 2609.7 2664.4 2666.3 2679.0 2680.0 2592.4 2594.4 2600.1 2631.0 2632.4 14563.1 14815.3 14815.9 14834.9 14871.7 7916.7 8173.3 8190.3 8194.3 8212.3 5313.5 5524.6 5627.7 5705.7 5711.2 1866.1 1914.7 1926.3 1978.3 1978.3 26116.1 26235.0 26365.3 26476.1 26498.1 6442.3 6673.7 6690.6 6839.3 6880.2 16748.7 16802.7 16824.7 16833.4 16846.7 7757.1 7871.4 7935.4 7951.7 7967.9 6018.9 6278.7 6446.9 6474.9 7259.1 5214.1 5359.2 5409.8 5445.5 5497.1 1537.2 1540.4 1566.1 1574.4 1582.7 883.5 902.2 913.5 921.9 924.8 7722.1 8207.8 8255.7 8410.7 8424.4 710.4 711.9 714.4 717.5 732.7 4540.1 4563.3 4579.0 4652.5 4689.0 21422.7 21946.9 22057.7 22063.3 22223.1 21012.7 21084.5 21564.8 21615.8 21893.2 10242.1 10350.2 10402.2 10439.3 10456.3 8460.5 8518.6 8528.1 8659.4 8733.6 17898.6 18219.5 18469.5 18625.9 18861.1 18598.3 18996.3 19222.6 19283.0 19409.7 10668.9 10885.9 10901.7 10914.7 10927.5 13046.9 13538.3 13555.1 13615.0 13634.3 11052.7 11160.1 11381.3 11571.1 11645.5 10994.2 11130.9 11385.4 11477.9 11711.4 9539.8 9569.6 9577.5 9603.5 9622.3 6791.0 6797.2 6884.4 6912.6 6972.0 6956.5 7060.1 7099.6 7189.1 7190.5 09:13:14 INFO - PID 6048 | 09:13:14 INFO - PID 6048 | [#4] prototype.html Cycles:180 Average:5612.00 Median:6296.07 stddev:1588.87 (25.2%) stddev-sans-first:1546.31 09:13:14 INFO - PID 6048 | Values: 500.2 510.1 526.6 534.4 541.0 6345.7 6361.4 6399.3 6467.2 6495.4 6019.7 6300.9 6324.5 6330.7 6340.9 5041.8 5059.5 5067.9 5078.0 5089.3 4900.5 5032.3 5044.3 5060.8 5089.0 5031.4 5036.9 5047.2 5066.9 5076.1 4977.0 4990.5 4997.8 5014.3 5052.4 4448.4 4511.7 4517.1 4535.8 4540.3 6185.6 6216.8 6246.4 6282.7 6291.3 5778.0 5827.6 5834.8 5835.0 5840.4 6574.2 6599.4 6687.2 6723.6 6734.4 4891.9 4895.0 4901.9 4906.3 4929.6 6985.8 7029.8 7060.9 7083.4 7092.6 5043.5 5065.2 5120.5 5128.4 5149.2 5127.9 5141.4 5160.4 5196.2 5215.9 5686.3 5703.9 5738.9 5760.9 5801.9 6674.9 6715.5 6722.0 6757.0 6768.2 1682.3 1723.2 1736.6 1740.5 1755.6 1185.7 1191.4 1235.5 1256.8 1268.8 5827.7 5841.6 5851.7 5886.2 5907.5 6603.4 6628.7 6659.9 6821.4 6882.8 5907.4 5949.6 5982.9 5999.2 6043.4 3605.6 3626.1 3637.4 3643.8 3650.5 6706.8 6713.1 6761.7 6788.4 6818.1 6873.2 6901.9 6955.7 7000.0 7072.8 6687.2 6728.9 6758.7 6782.3 6825.3 6863.1 6905.3 6948.8 6962.0 6969.0 6762.6 6865.9 6866.1 6886.0 6895.9 6583.5 6654.4 6716.9 6758.6 6763.2 4957.6 4993.6 4999.2 5033.8 5078.6 6642.7 6831.5 6877.4 6970.5 7003.6 6526.9 6543.5 6599.4 6607.3 6676.9 6506.7 6571.5 6624.8 6661.7 6668.4 6447.8 6490.6 6530.3 6560.1 6580.8 6438.6 6553.4 6591.6 6592.4 6652.8 6347.9 6446.2 6504.9 6548.7 6571.2 09:13:14 INFO - PID 6048 | 09:13:14 INFO - PID 6048 | [#5] yui.html Cycles:180 Average:23964.55 Median:21705.51 stddev:32772.66 (151.0%) stddev-sans-first:32818.70 09:13:14 INFO - PID 6048 | Values: 867.6 895.2 898.0 905.1 907.1 18014.6 19347.5 19404.8 19420.2 19500.8 14865.3 14925.3 16679.1 20755.1 20823.8 966.3 968.2 974.1 976.1 977.0 989.8 995.7 996.4 997.6 998.4 736.5 759.0 998.0 1042.0 1051.2 1026.5 1027.2 1036.8 1040.5 1040.5 753.4 757.3 764.1 766.5 767.8 18721.6 18939.4 19979.5 20229.0 20299.8 21207.2 21304.8 21808.9 21925.1 22121.0 17168.8 17365.5 17458.9 17615.1 17658.9 3708.7 3709.2 3710.7 3714.5 3726.1 129859.0 132174.3 133548.0 133933.0 135231.3 16420.5 16440.3 16605.9 16668.6 16835.9 28007.0 28945.7 29318.0 30218.1 31299.4 8173.6 8211.7 8220.6 8274.5 8288.5 4054.7 4107.8 4227.2 4242.5 4267.1 5252.7 5364.9 5373.5 5377.8 5393.1 975.8 986.4 995.8 1006.6 1008.3 687.6 690.1 703.7 711.4 713.8 11409.2 11474.2 11654.6 12869.8 13109.9 684.9 689.9 695.7 703.9 715.8 5508.0 5609.7 5618.2 5642.7 5730.7 105163.5 106352.0 106550.5 107736.5 108642.5 93694.5 94542.0 96744.0 97377.5 97429.5 85543.1 86523.0 86604.1 86945.6 90186.5 10291.9 10464.6 10512.3 10596.8 10719.7 41260.4 41423.2 41530.2 42112.6 42365.6 75683.6 75853.2 75991.6 77411.6 78201.6 18900.7 18934.5 18972.7 19314.0 19336.6 20400.8 20943.8 20945.5 20975.0 21602.1 16804.5 16835.3 17062.9 19100.2 19789.7 17708.8 17836.9 18817.0 19218.0 19556.7 17302.7 17508.3 17914.5 18143.9 19405.9 12986.9 13164.3 13625.8 14172.0 14372.0 13482.0 13722.4 13740.7 13766.9 13879.5 09:13:14 INFO - PID 6048 | -------- Summary: end -------- 09:13:14 INFO - PID 6048 | 09:13:15 INFO - TEST-INFO | 6048: exit 0 09:13:15 INFO - TEST-OK | dromaeo_css | took 1116032ms 09:13:15 INFO - TEST-START | kraken 09:13:15 INFO - Initialising browser for kraken test... 09:13:15 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33086/getInfo.html -profile /tmp/tmp4RNjYj/profile 09:13:15 INFO - TEST-INFO | started process 6438 (/builds/slave/test/build/application/firefox/firefox http://localhost:33086/getInfo.html) 09:13:21 INFO - TEST-INFO | 6438: exit 0 09:13:21 INFO - Browser initialized. 09:13:21 INFO - Running cycle 1/1 for kraken test... 09:13:21 INFO - Using env: {'DISPLAY': ':0', 09:13:21 INFO - 'HOME': '/home/cltbld', 09:13:21 INFO - 'JSGC_DISABLE_POISONING': '1', 09:13:21 INFO - 'LANG': 'en_US.UTF-8', 09:13:21 INFO - 'LANGUAGE': 'en_US:en', 09:13:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:13:21 INFO - 'LOGNAME': 'cltbld', 09:13:21 INFO - 'MAIL': '/var/mail/cltbld', 09:13:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:13:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:13:21 INFO - 'MOZ_ACCELERATED': '1', 09:13:21 INFO - 'MOZ_CRASHREPORTER': '1', 09:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:13:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:13:21 INFO - 'MOZ_NO_REMOTE': '1', 09:13:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:13:21 INFO - 'MOZ_WEBRENDER': '1', 09:13:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:13:21 INFO - 'NO_EM_RESTART': '1', 09:13:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:13:21 INFO - 'PWD': '/builds/slave/test', 09:13:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:13:21 INFO - 'RUST_BACKTRACE': 'full', 09:13:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:13:21 INFO - 'SHELL': '/bin/bash', 09:13:21 INFO - 'SHLVL': '1', 09:13:21 INFO - 'TERM': 'linux', 09:13:21 INFO - 'TMOUT': '86400', 09:13:21 INFO - 'USER': 'cltbld', 09:13:21 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185', 09:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:13:21 INFO - '_': '/tools/buildbot/bin/python'} 09:13:21 INFO - TEST-INFO | started process 6635 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4RNjYj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:22 INFO - PID 6635 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:13:23 INFO - PID 6635 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 09:13:23 INFO - PID 6635 | 09:13:23 INFO - PID 6635 | (/builds/slave/test/build/application/firefox/firefox:6698): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:13:23 INFO - PID 6635 | 09:13:33 INFO - PID 6635 | RSS: Main: 205983744 09:13:33 INFO - PID 6635 | 09:13:36 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33086/tests/kraken/driver.html?testName=audio-beat-detection) 09:13:37 INFO - PID 6635 | RSS: Main: 211308544 09:13:37 INFO - PID 6635 | 09:13:39 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33086/tests/kraken/driver.html?testName=audio-dft) 09:13:40 INFO - PID 6635 | RSS: Main: 212393984 09:13:40 INFO - PID 6635 | 09:13:43 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33086/tests/kraken/driver.html?testName=audio-fft) 09:13:44 INFO - PID 6635 | RSS: Main: 206643200 09:13:44 INFO - PID 6635 | 09:13:46 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33086/tests/kraken/driver.html?testName=audio-oscillator) 09:13:47 INFO - PID 6635 | RSS: Main: 207335424 09:13:47 INFO - PID 6635 | 09:13:49 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33086/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:13:49 INFO - PID 6635 | RSS: Main: 207929344 09:13:49 INFO - PID 6635 | 09:13:53 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33086/tests/kraken/driver.html?testName=imaging-darkroom) 09:13:53 INFO - PID 6635 | RSS: Main: 211505152 09:13:53 INFO - PID 6635 | 09:13:57 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33086/tests/kraken/driver.html?testName=imaging-desaturate) 09:13:57 INFO - PID 6635 | RSS: Main: 210022400 09:13:57 INFO - PID 6635 | 09:14:00 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33086/tests/kraken/driver.html?testName=json-parse-financial) 09:14:01 INFO - PID 6635 | RSS: Main: 211251200 09:14:01 INFO - PID 6635 | 09:14:03 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33086/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:14:03 INFO - PID 6635 | RSS: Main: 210964480 09:14:03 INFO - PID 6635 | 09:14:05 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33086/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:14:06 INFO - PID 6635 | RSS: Main: 210219008 09:14:06 INFO - PID 6635 | 09:14:08 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33086/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:14:08 INFO - PID 6635 | RSS: Main: 210685952 09:14:08 INFO - PID 6635 | 09:14:11 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33086/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:14:12 INFO - PID 6635 | RSS: Main: 211337216 09:14:12 INFO - PID 6635 | 09:14:15 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33086/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:14:15 INFO - PID 6635 | RSS: Main: 212295680 09:14:15 INFO - PID 6635 | 09:14:17 INFO - PID 6635 | Cycle 1(1): loaded http://localhost:33086/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33086/tests/kraken/driver.html?testName=ai-astar) 09:14:17 INFO - PID 6635 | RSS: Main: 214016000 09:14:17 INFO - PID 6635 | 09:14:17 INFO - PID 6635 | __start_tp_report 09:14:18 INFO - PID 6635 | _x_x_mozilla_page_load 09:14:18 INFO - PID 6635 | _x_x_mozilla_page_load_details 09:14:18 INFO - PID 6635 | |i|pagename|runs| 09:14:18 INFO - PID 6635 | |0;ai-astar;108;115;97;108;110;109;109;114;113;108 09:14:18 INFO - PID 6635 | |1;audio-beat-detection;150;116;143;181;117;157;150;118;182;118 09:14:18 INFO - PID 6635 | |2;audio-dft;173;169;163;165;168;170;180;169;167;164 09:14:18 INFO - PID 6635 | |3;audio-fft;80;77;73;175;75;83;74;79;165;75 09:14:18 INFO - PID 6635 | |4;audio-oscillator;79;79;130;83;84;84;129;83;80;133 09:14:18 INFO - PID 6635 | |5;imaging-gaussian-blur;114;127;118;115;115;115;115;114;116;119 09:14:18 INFO - PID 6635 | |6;imaging-darkroom;155;163;157;156;157;148;148;150;156;156 09:14:18 INFO - PID 6635 | |7;imaging-desaturate;97;98;99;102;100;100;95;96;94;95 09:14:18 INFO - PID 6635 | |8;json-parse-financial;56;55;59;56;54;54;58;58;61;54 09:14:18 INFO - PID 6635 | |9;json-stringify-tinderbox;39;43;45;39;48;47;39;38;46;40 09:14:18 INFO - PID 6635 | |10;stanford-crypto-aes;76;84;79;81;78;79;76;76;76;75 09:14:18 INFO - PID 6635 | |11;stanford-crypto-ccm;120;126;120;118;127;116;125;138;125;162 09:14:18 INFO - PID 6635 | |12;stanford-crypto-pbkdf2;176;176;177;176;176;175;174;181;177;174 09:14:18 INFO - PID 6635 | |13;stanford-crypto-sha256-iterative;66;67;66;64;64;75;64;71;67;70 09:14:18 INFO - PID 6635 | __end_tp_report 09:14:18 INFO - PID 6635 | __start_cc_report 09:14:18 INFO - PID 6635 | _x_x_mozilla_cycle_collect,947 09:14:18 INFO - PID 6635 | __end_cc_report 09:14:18 INFO - PID 6635 | __startTimestamp1504282457996__endTimestamp 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | ------- Summary: start ------- 09:14:18 INFO - PID 6635 | Number of tests: 14 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#0] ai-astar Cycles:10 Average:109.10 Median:111.50 stddev:5.00 (4.5%) stddev-sans-first:5.29 09:14:18 INFO - PID 6635 | Values: 108.0 115.0 97.0 108.0 110.0 109.0 109.0 114.0 113.0 108.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#1] audio-beat-detection Cycles:10 Average:143.20 Median:150.00 stddev:25.63 (17.1%) stddev-sans-first:27.07 09:14:18 INFO - PID 6635 | Values: 150.0 116.0 143.0 181.0 117.0 157.0 150.0 118.0 182.0 118.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#2] audio-dft Cycles:10 Average:168.80 Median:169.00 stddev:4.94 (2.9%) stddev-sans-first:5.00 09:14:18 INFO - PID 6635 | Values: 173.0 169.0 163.0 165.0 168.0 170.0 180.0 169.0 167.0 164.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#3] audio-fft Cycles:10 Average:95.60 Median:76.00 stddev:39.40 (51.8%) stddev-sans-first:41.38 09:14:18 INFO - PID 6635 | Values: 80.0 77.0 73.0 175.0 75.0 83.0 74.0 79.0 165.0 75.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:81.50 stddev:23.74 (29.1%) stddev-sans-first:24.33 09:14:18 INFO - PID 6635 | Values: 79.0 79.0 130.0 83.0 84.0 84.0 129.0 83.0 80.0 133.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:115.50 stddev:3.94 (3.4%) stddev-sans-first:4.04 09:14:18 INFO - PID 6635 | Values: 114.0 127.0 118.0 115.0 115.0 115.0 115.0 114.0 116.0 119.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#6] imaging-darkroom Cycles:10 Average:154.60 Median:156.00 stddev:4.67 (3.0%) stddev-sans-first:4.95 09:14:18 INFO - PID 6635 | Values: 155.0 163.0 157.0 156.0 157.0 148.0 148.0 150.0 156.0 156.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:95.50 stddev:2.63 (2.8%) stddev-sans-first:2.78 09:14:18 INFO - PID 6635 | Values: 97.0 98.0 99.0 102.0 100.0 100.0 95.0 96.0 94.0 95.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:57.00 stddev:2.42 (4.2%) stddev-sans-first:2.55 09:14:18 INFO - PID 6635 | Values: 56.0 55.0 59.0 56.0 54.0 54.0 58.0 58.0 61.0 54.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#9] json-stringify-tinderbox Cycles:10 Average:42.40 Median:44.00 stddev:3.84 (8.7%) stddev-sans-first:3.87 09:14:18 INFO - PID 6635 | Values: 39.0 43.0 45.0 39.0 48.0 47.0 39.0 38.0 46.0 40.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.50 stddev:2.83 (3.6%) stddev-sans-first:2.91 09:14:18 INFO - PID 6635 | Values: 76.0 84.0 79.0 81.0 78.0 79.0 76.0 76.0 76.0 75.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:125.50 stddev:13.54 (10.8%) stddev-sans-first:14.07 09:14:18 INFO - PID 6635 | Values: 120.0 126.0 120.0 118.0 127.0 116.0 125.0 138.0 125.0 162.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.20 Median:176.00 stddev:1.99 (1.1%) stddev-sans-first:2.11 09:14:18 INFO - PID 6635 | Values: 176.0 176.0 177.0 176.0 176.0 175.0 174.0 181.0 177.0 174.0 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:3.60 (5.4%) stddev-sans-first:3.78 09:14:18 INFO - PID 6635 | Values: 66.0 67.0 66.0 64.0 64.0 75.0 64.0 71.0 67.0 70.0 09:14:18 INFO - PID 6635 | -------- Summary: end -------- 09:14:18 INFO - PID 6635 | 09:14:18 INFO - PID 6635 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:14:18 INFO - TEST-INFO | 6635: exit 0 09:14:19 INFO - TEST-OK | kraken | took 63693ms 09:14:19 INFO - SUITE-END | took 1179s 09:14:19 INFO - Completed test suite (00:19:39) 09:14:19 INFO - javascript benchmark 09:14:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8615.521785298302, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.6728327167284, 3302.058913209735, 3319.751018673597, 3334.4164771165047, 3354.983225083874, 6309.211348581427, 6350.587211831239, 6352.936470635293, 6389.201349831265, 6446.419822215999, 6079.179310793042, 6130.44825965663, 6160.106784516242, 6200.821843849665, 6217.720202590883, 5835.095560188178, 5854.063349864023, 5863.8534036649, 5871.266091738533, 5962.761489540413, 5950.0, 5988.3712210217955, 5991.640501569898, 5999.730012149464, 6038.456538911497, 1052.1988061883294, 5762.683052432115, 5929.555283353744, 5977.0, 6020.729067191987, 5835.854103647401, 5856.326522449887, 5869.7945571904775, 5965.731542080617, 5974.133750606181, 5842.503387212092, 5863.706814659293, 5933.881322373529, 5955.910661340084, 5956.791512297049, 6249.78125765596, 6294.0873573332065, 6352.07894855248, 6394.6163230205875, 6449.83875403114, 6805.047293378931, 6846.4522838172825, 6881.0, 6898.0687607172895, 6921.169459664822, 6830.487713421513, 6850.177978642545, 6872.62200578968, 6937.132858392701, 6943.236244013155, 6270.937290627081, 6280.371962803729, 6295.96852015737, 6346.904796428011, 6411.807645770541, 7070.505064645528, 7123.786286411416, 7126.964365178244, 7134.393576545948, 7141.285871412921, 6714.630695311707, 6729.293424190488, 6751.459883209332, 6815.829604259834, 6821.283765204582, 6612.107365505595, 6742.056112144303, 6789.287124851919, 6904.206016308189, 6961.129858767654, 6571.769988050395, 6630.30381809903, 6642.800715978528, 6649.368310010541, 6692.866142677217, 6598.274189839114, 6614.470842332602, 6677.933220667828, 6689.297623749533, 6698.96650516754, 5286.5506431952945, 5305.628605997543, 5311.150215965427, 5312.681239125664, 5315.069862773999, 4523.932141017887, 4681.765911704401, 4685.086408150446, 4737.076270127292, 4791.904161916742, 6000.33996260411, 6003.699815009232, 6076.3316035236085, 6077.24034495688, 6086.726097325631, 6702.329767023259, 6848.554843935129, 6865.897011544831, 6947.5831450113155, 6952.374286314256, 5921.73352199152, 5931.406859314034, 5945.583809133319, 5945.613535120204, 5963.970180149072, 5859.736311865976, 5869.266341707286, 5884.058550631964, 5886.470217680429, 5915.940840591625, 6618.271990081087, 6644.269130395653, 6649.135612370361, 6697.196336439659, 6721.529492935448, 6648.700808463631, 6710.66446677674, 6710.698018589273, 6719.966400167968, 6724.4284235840505, 6615.37153970372, 6624.436922861611, 6629.370209830058, 6630.602163870183, 6643.50173736962, 6508.902366464602, 6735.797926062226, 6743.5953842769595, 6745.089412929192, 6767.627780472121, 6824.317568243136, 6852.897206542006, 6899.137607799025, 6951.0, 6979.790606281819, 6476.0, 6569.671516424255, 6618.801435956929, 6709.832254193684, 6725.0, 6582.1443212384, 6596.70314835824, 6604.405603495708, 6640.269570347161, 6656.134702488645, 6584.209894812653, 6611.173603299588, 6625.204975402789, 6629.602223866584, 6631.3700198481065, 6651.102101216274, 6659.101021362054, 6680.565763225375, 6720.596764194164, 6767.89848152288, 6457.096006558992, 6471.158749362553, 6516.6415847128865, 6540.149780528501, 6555.967220163869], "value": 6192.765024553051, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.4244316762428, 829.7344849648108, 856.4689892266795, 882.5454890731271, 887.9023307436181, 39300.0, 40223.59776402235, 40348.0, 40576.59423405765, 40791.592084079224, 37064.629353706456, 37199.256014879684, 37297.81351093247, 37393.06517337061, 37466.063348416305, 6918.238993710688, 6971.3028697130385, 7029.261927497616, 7043.577385356869, 7046.224915259343, 6906.827329316757, 6939.167299924016, 6981.301869813004, 6982.860342793141, 6983.2318444971015, 6937.0, 7554.169041405441, 7720.227977202291, 7727.690892364299, 7730.9226907731045, 7566.621668916532, 7613.733519326853, 7625.31372176504, 7674.424418168632, 7675.577843218621, 7473.476856620039, 7480.513766605153, 7646.044244469442, 7665.386769058462, 7666.9616651916385, 36673.44989825155, 36694.0, 37008.0, 37107.62892371069, 37111.628883711084, 3523.1016090896915, 3523.4890940813693, 3538.6815186633326, 3567.1973805893726, 3570.3751843427294, 31186.532202016988, 31428.842855785802, 31548.0, 31565.21086972821, 31595.84202078998, 4980.800767969277, 5131.615128865313, 5149.665271757362, 5164.277001219794, 5216.061109000379, 182145.0, 184332.0, 185453.07273463547, 185477.0, 185584.0, 48189.27716084261, 48195.277070843964, 48528.5147148524, 48616.51383486191, 48724.756376218604, 50562.494375056514, 51221.743891280305, 52480.0, 52649.2102618461, 52835.0, 14794.926025369805, 14938.850611493746, 15061.849381506263, 15107.848921510864, 15129.0, 12332.445039973223, 12341.50633974631, 12353.135280530456, 12377.62867113988, 12380.690482737824, 13471.528496502575, 13761.105528140639, 13768.518101866586, 13826.585202443943, 13880.444782208599, 5361.249425080492, 5437.619366644297, 5542.865996591261, 5546.084895992197, 5564.582656300794, 991.4299277915084, 1000.7598176437602, 1012.9392236465688, 1018.0, 1033.571068006784, 23003.079876805074, 23081.192158274378, 23173.073077077064, 23439.765602344098, 23521.76478235196, 1086.7989421956963, 1110.9055730262858, 1113.9777204455866, 1115.5537784885948, 1131.4399372310759, 12477.251364918135, 12549.372531373394, 16020.439284625216, 16061.196940153179, 16638.83361166397, 231443.0, 232716.0, 233667.0, 234281.0, 234669.0, 47149.292760608616, 48375.5162448371, 48452.27321590179, 55699.0, 56580.434195657515, 1256.3592567790492, 1261.703499677575, 1264.2730430002784, 1267.480333063421, 1373.4300265389952, 26480.0, 26587.93648254048, 26802.59796103099, 27010.86494567593, 27329.31676708098, 37007.0, 37175.81412092922, 37201.25597488089, 37333.0, 37502.62497374992, 208648.0, 209146.0, 209888.0, 210946.0, 211260.0, 31273.374532508766, 32093.234872081324, 32320.0, 32542.674573253964, 33068.0, 40072.3989140169, 40701.592984070965, 40835.38746918739, 40951.7952410236, 41143.38284925669, 30372.84813575918, 30642.233944151576, 30942.0, 31165.53251701181, 31331.686683132877, 30236.546451802802, 30462.238444039078, 30730.53904191394, 30991.535126972663, 31492.0, 33929.83035084809, 34257.14357141092, 34313.828430857684, 36430.4535431974, 36998.44502332521, 31897.0, 32384.83807580947, 32487.0, 32772.67227327696, 32817.0, 24702.382440439134, 24782.628260575744, 24790.628140577544, 25041.74958250467, 25568.48863022692], "value": 16913.548048130157, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [569.7464628240433, 572.2960758267332, 572.3246569048526, 572.6392372805133, 579.336659524844, 21902.561948761017, 22828.54342913141, 22873.656895146567, 22881.42796430086, 22919.312420627368, 23561.293161205147, 26226.606600900956, 26313.0, 26328.47343053138, 26427.0, 6719.092922455455, 6827.0, 6867.244603093655, 6870.416014638762, 6881.724731010779, 6413.454856337217, 6734.797956061326, 6764.458843292549, 7001.509894307401, 7032.0506731591095, 7014.473914456411, 7050.647467626623, 7051.7884463465925, 7061.823454413629, 7064.540804847694, 6976.686049127776, 7054.788356349318, 7062.788116356492, 7063.470239732041, 7067.257937916522, 6826.897596536056, 6839.863202735967, 6855.485838562127, 6910.239873613898, 6915.792526224222, 30238.0, 30616.38767224643, 30891.0, 31806.681933180604, 32019.679803201903, 2078.116800359841, 2103.4846462616606, 2105.7473103227558, 2116.1958455786857, 2119.8304135669264, 20926.162953481842, 21324.360269191948, 21506.139754409804, 21557.676634850333, 21605.027773750062, 2518.55925213087, 2533.987330063338, 2633.670791151106, 2637.6966648835437, 2657.800664950117, 136527.999999999, 144184.0, 144350.0, 144504.277478613, 144851.27574362166, 19282.517937051685, 21209.36371908814, 21506.89246553757, 21795.44216224884, 21834.781652183276, 43259.134817304104, 43600.12799743987, 43638.34542481865, 44045.55954440415, 44312.56280904581, 9430.0, 9816.65641702537, 9974.601015959426, 9974.700758977096, 10121.24090693201, 8985.101489850962, 9007.684731034513, 9176.6788162414, 9233.261339092856, 9303.441793492411, 7731.729389471341, 8987.236084932854, 9155.587998540082, 9278.164965153033, 9290.349675522784, 2779.360747028195, 2800.523910935146, 2825.1948194764395, 2832.3344014156655, 2951.985240073786, 958.5494817435793, 976.4043933200742, 987.5605355616825, 988.8566157907208, 1000.3547711726042, 19683.0, 19828.60342793165, 19986.60026799456, 20021.29925452631, 20416.489587760425, 1037.6575730009079, 1045.6131231444415, 1054.4095306628312, 1056.4612047855649, 1059.5179193466954, 10592.788144237073, 11005.229633925548, 11101.888981110085, 11172.497237624326, 11235.325880447199, 469504.0, 489582.0, 490985.0, 492866.0, 499441.0, 114824.0, 116752.0, 118226.0, 118968.40515797366, 120124.3993780043, 1190.0776897904193, 1202.3146806320312, 1208.353530860985, 1216.1393068605048, 1274.1781550899802, 20464.283750069273, 20792.168313267906, 20929.790702092785, 20993.475163121046, 21214.575708486052, 53769.0, 55784.0, 56532.999999998356, 56764.0, 57529.71235143798, 68711.0, 71732.28267717257, 72312.63843680748, 72459.0, 73431.63284183545, 12016.158868879444, 12466.127371083938, 14018.859811401755, 14489.637759056108, 18526.814731852508, 23183.304500865, 23191.88404057969, 23384.363094583216, 23403.531929360975, 23411.76588234096, 18220.0, 18632.906835465736, 18709.43871683852, 18879.339223127125, 18948.526286842938, 16227.0, 16615.50153495397, 16779.328826846788, 17203.483895483154, 17476.126193690518, 18473.353432629792, 18748.81251187471, 19738.40784776459, 19771.0, 19935.700964485255, 20174.0, 20674.276400325918, 20757.584848303253, 20847.64490308125, 20887.8955605221, 17922.19350129221, 18797.624047519246, 18817.0, 18844.811551884854, 18853.528661783566], "value": 12739.194711428383, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.2371624457815, 1348.732191739764, 1360.1770928588207, 1361.8706222908827, 1372.5196181336532, 14709.41162353506, 15081.622959425993, 15375.077495350286, 15385.846141538581, 15405.152716600582, 15374.38502459903, 15764.527064188063, 15811.0, 15812.762808557853, 15862.36550537977, 2795.6365672462653, 2811.578263260507, 2835.248659105339, 2836.602875597418, 2837.1914004508717, 2675.652165218519, 2742.4103817679197, 2759.296379423244, 2774.0706863200853, 2774.3480282133783, 2748.5464898291757, 2777.8611069446547, 2778.8610569471443, 2779.0967935420967, 2780.402213524092, 2609.7390260973843, 2664.373872140055, 2666.3467450474564, 2679.0489376271435, 2679.9598006029923, 2592.3519120219944, 2594.4292255703713, 2600.1029644772525, 2630.9605355919675, 2632.3813903732607, 14563.126212427289, 14815.333310001075, 14815.851841481555, 14834.925825370912, 14871.702565948728, 7916.6833326666865, 8173.346132309401, 8190.303824174952, 8194.344452443791, 8212.301954333889, 5313.521783039507, 5524.58565607581, 5627.6904770237625, 5705.686187259699, 5711.228984087136, 1866.122922226565, 1914.731937528747, 1926.3257859748976, 1978.3174804692358, 1978.3273686946507, 26116.085936992306, 26235.0, 26365.3408664783, 26476.07333743329, 26498.07256746024, 6442.259140198843, 6673.733050678015, 6690.59856408617, 6839.31606839322, 6880.2431732509385, 16748.66502669964, 16802.663946720997, 16824.74762878558, 16833.41083062087, 16846.747298790528, 7757.146713861471, 7871.409644276588, 7935.365170786324, 7951.721689740832, 7967.9203207967175, 6018.879622407528, 6278.717457714414, 6446.871062578723, 6474.935250647433, 7259.092613423322, 5214.13966695491, 5359.22291267764, 5409.83770486886, 5445.455454454602, 5497.120460726264, 1537.2467490929587, 1540.414642435867, 1566.0838409530552, 1574.3702518992495, 1582.7071991681346, 883.5228976352826, 902.228594551662, 913.4564933864473, 921.8755468011867, 924.7872989212519, 7722.073351197892, 8207.753767386988, 8255.711050113337, 8410.747677569683, 8424.368172386972, 710.4458522352573, 711.9430445564342, 714.3784907130726, 717.476242343082, 732.7032551816523, 4540.081104942552, 4563.338315944169, 4579.015511665008, 4652.464966528825, 4688.9531104689195, 21422.678659820114, 21946.89026554857, 22057.669134962667, 22063.33809985703, 22223.11107555712, 21012.684809727867, 21084.47288817792, 21564.784352156592, 21615.783842161378, 21893.233736819137, 10242.078212960869, 10350.223733219887, 10402.219833512518, 10439.321444106108, 10456.32033917793, 8460.534670593177, 8518.574071296534, 8528.147185281423, 8659.393842430969, 8733.606987685696, 17898.5525361867, 18219.45341639753, 18469.53826154357, 18625.906870466104, 18861.05694715286, 18598.349057782914, 18996.335128270443, 19222.61554768869, 19283.0, 19409.685453921466, 10668.89331106679, 10885.891141088488, 10901.727456813644, 10914.672559823219, 10927.508262128064, 13046.869531304565, 13538.323083845966, 13555.051146419655, 13615.0, 13634.250116243702, 11052.723681907695, 11160.107191424504, 11381.317120972437, 11571.074314055024, 11645.534178632759, 10994.230403871652, 11130.944345278222, 11385.430728463709, 11477.942610286895, 11711.414429278673, 9539.761505962406, 9569.569369378534, 9577.521123943914, 9603.471809050568, 9622.326437149612, 6790.966045169743, 6797.1843378794865, 6884.4148247399535, 6912.617476504619, 6972.0, 6956.478264130312, 7060.082189315356, 7099.609521476162, 7189.065421495172, 7190.4966652335925], "value": 6560.288985268177, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.24213316825006, 510.12004292595253, 526.6260954722147, 534.3854567247662, 540.9675419474831, 6345.650989195592, 6361.363863613637, 6399.27219650694, 6467.214261446525, 6495.447886929611, 6019.729112189951, 6300.8729282320155, 6324.462420694235, 6330.683465826702, 6340.873182536347, 5041.773120209581, 5059.468755780646, 5067.873303167414, 5077.974610126954, 5089.338386009814, 4900.460949295574, 5032.295478632995, 5044.268581055745, 5060.797568097271, 5088.974555127219, 5031.371078615173, 5036.949630503685, 5047.242913562958, 5066.949330506703, 5076.111680455924, 4976.950230497685, 4990.47600002, 4997.750112494379, 5014.323066386028, 5052.427553064483, 4448.421705178338, 4511.661625378109, 4517.051419201972, 4535.818567257305, 4540.296254080623, 6185.5979361341815, 6216.782412615582, 6246.437820596123, 6282.654454005028, 6291.269900777323, 5778.0, 5827.592068555203, 5834.824955251349, 5834.97082514589, 5840.3575606683235, 6574.211094668671, 6599.373059559334, 6687.1975362956755, 6723.5965842049145, 6734.420593996081, 4891.926621100651, 4895.04198320671, 4901.901961960741, 4906.264060390934, 4929.605631549503, 6985.790426287169, 7029.753958611424, 7060.894086588705, 7083.433325333961, 7092.609906455143, 5043.4956504349275, 5065.164247899129, 5120.462351453119, 5128.4101928402315, 5149.227615857576, 5127.923191107533, 5141.408737995179, 5160.380754309432, 5196.246544251062, 5215.947840521622, 5686.289213848269, 5703.942960570341, 5738.913916291259, 5760.942390576124, 5801.9129713054335, 6674.933250667528, 6715.529912906148, 6722.0, 6757.0, 6768.2216545097945, 1682.2682133272067, 1723.2073246306588, 1736.5919009032866, 1740.4604572582539, 1755.5698853780905, 1185.6680129563736, 1191.362620997762, 1235.5428491458217, 1256.8491780986158, 1268.7843066678686, 5827.679477628772, 5841.649501029951, 5851.6781577012825, 5886.205362276123, 5907.527397808165, 6603.438707709898, 6628.734850605922, 6659.9031450375805, 6821.3519715626935, 6882.827929301708, 5907.379725128885, 5949.553783466257, 5982.850428739229, 5999.160117583542, 6043.398499397663, 3605.5853576838476, 3626.1297288650535, 3637.381645120336, 3643.8339731285732, 3650.5071815304595, 6706.798796036127, 6713.1272934519175, 6761.695723692445, 6788.389044985975, 6818.147731533559, 6873.20958089816, 6901.896471552931, 6955.739384699993, 7000.0, 7072.752453664097, 6687.230968438692, 6728.947670960404, 6758.695858686371, 6782.321767823277, 6825.317468253234, 6863.142107236596, 6905.343992320722, 6948.756793512202, 6962.0, 6968.965155174192, 6762.628055456997, 6865.862682746417, 6866.081915186126, 6886.0, 6895.862082758216, 6583.539152259296, 6654.367835055662, 6716.899246511209, 6758.594484330956, 6763.222229443677, 4957.553820156203, 4993.575546078624, 4999.175136102503, 5033.773480193473, 5078.619103567174, 6642.667866606747, 6831.521793474409, 6877.415419689382, 6970.512064155663, 7003.614801185984, 6526.934730652632, 6543.476521878143, 6599.4390476808085, 6607.3062328456745, 6676.866462670817, 6506.707198175999, 6571.5399922005, 6624.8012559623285, 6661.700223490052, 6668.366505182, 6447.806565803034, 6490.578112422678, 6530.346965303622, 6560.081588577698, 6580.80257592273, 6438.6136831790245, 6553.4101930826455, 6591.604503729792, 6592.373724496165, 6652.800415987528, 6347.873042539216, 6446.194225721785, 6504.902426463512, 6548.705308261045, 6571.211454625475], "value": 5141.522350322232, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [867.6486023160618, 895.247991686983, 897.9865302020471, 905.1355955062916, 907.0793144957867, 18014.639707205864, 19347.516312092186, 19404.805951940478, 19420.223191072375, 19500.804991950077, 14865.331060102295, 14925.328360223788, 16679.08265045422, 20755.06602202901, 20823.791762082375, 966.3332300712503, 968.1867231525517, 974.0843607009424, 976.077606661705, 976.9560369783377, 989.7723523589578, 995.706266651336, 996.4370130876068, 997.5760301871712, 998.3610489286863, 736.532301988236, 759.0360242492031, 997.9850302245472, 1042.0361165921531, 1051.1695760349314, 1026.5226669598599, 1027.1577306608583, 1036.7874585709912, 1040.5057597641135, 1040.5473618975732, 753.427395179662, 757.3448966643869, 764.109812068939, 766.4596459496098, 767.8425922685836, 18721.62556748871, 18939.43181704551, 19979.50051248716, 20229.0, 20299.797002030085, 21207.151713931424, 21304.786952130435, 21808.89095554528, 21925.122995080335, 22121.0, 17168.828311716847, 17365.479035628825, 17458.91270543652, 17615.119244037876, 17658.91170544152, 3708.6662200402093, 3709.2025214578725, 3710.7216958728204, 3714.461403096536, 3726.142987112952, 129859.0, 132174.3391283047, 133548.0, 133933.0, 135231.32384338015, 16420.507384778477, 16440.260188291555, 16605.91697041507, 16668.58328541796, 16835.915820421065, 28007.0, 28945.71054289472, 29318.0, 30218.09345719632, 31299.37401252008, 8173.632186551619, 8211.671533138608, 8220.63007164679, 8274.462159959583, 8288.502689838508, 4054.655354294859, 4107.774072425986, 4227.196832601796, 4242.490901091827, 4267.061246525759, 5252.684838909678, 5364.946350536523, 5373.543248823894, 5377.784888604412, 5393.1370980642905, 975.7755716185317, 986.3539381704994, 995.7560397702464, 1006.6174853555748, 1008.253892119826, 687.6183718036538, 690.1132045321738, 703.6868593475854, 711.4379640084379, 713.8001359619315, 11409.201355905006, 11474.196806223652, 11654.592089276835, 12869.806952895715, 13109.934450327688, 684.8767221900106, 689.8827199376118, 695.6695569604398, 703.8979347994513, 715.8425146467769, 5508.008558459515, 5609.719514024282, 5618.157276408571, 5642.6614403135945, 5730.656160630376, 105163.47418263013, 106352.0, 106550.46724766327, 107736.46131769291, 108642.45678771714, 93694.53152734193, 94542.0, 96744.0, 97377.51311243398, 97429.51285243529, 85543.14456855477, 86523.0, 86604.13395866087, 86945.56527217323, 90186.54906725425, 10291.897081029094, 10464.581416743245, 10512.335853561663, 10596.788064238517, 10719.732006699895, 41260.38109428301, 41423.1715365697, 41530.1693966125, 42112.57887421209, 42365.576344236164, 75683.62158189174, 75853.24146758462, 75991.62004190165, 77411.61294193719, 78201.60899195468, 18900.716489252947, 18934.52663683419, 18972.71540926915, 19314.0, 19336.613267734847, 20400.79599203989, 20943.790562094186, 20945.476363091046, 20975.0, 21602.13591456324, 16804.495865124067, 16835.326586936386, 17062.914685426495, 19100.23599056022, 19789.703154452407, 17708.822911770716, 17836.948750893815, 18817.0, 19218.0, 19556.706649399985, 17302.65394692074, 17508.299668013136, 17914.46256612304, 18143.909280453514, 19405.902970485622, 12986.870131298567, 13164.275964822027, 13625.833766026139, 14172.00795944274, 14372.0, 13482.0, 13722.382492787649, 13740.725185496434, 13766.93116534411, 13879.514217002357], "value": 9086.31116575891, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 97.0, 108.0, 110.0, 109.0, 109.0, 114.0, 113.0, 108.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 143.0, 181.0, 117.0, 157.0, 150.0, 118.0, 182.0, 118.0], "value": 143.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 169.0, 163.0, 165.0, 168.0, 170.0, 180.0, 169.0, 167.0, 164.0], "value": 168.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 73.0, 175.0, 75.0, 83.0, 74.0, 79.0, 165.0, 75.0], "value": 95.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 130.0, 83.0, 84.0, 84.0, 129.0, 83.0, 80.0, 133.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 127.0, 118.0, 115.0, 115.0, 115.0, 115.0, 114.0, 116.0, 119.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 163.0, 157.0, 156.0, 157.0, 148.0, 148.0, 150.0, 156.0, 156.0], "value": 154.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 99.0, 102.0, 100.0, 100.0, 95.0, 96.0, 94.0, 95.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 59.0, 56.0, 54.0, 54.0, 58.0, 58.0, 61.0, 54.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 43.0, 45.0, 39.0, 48.0, 47.0, 39.0, 38.0, 46.0, 40.0], "value": 42.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 84.0, 79.0, 81.0, 78.0, 79.0, 76.0, 76.0, 76.0, 75.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 126.0, 120.0, 118.0, 127.0, 116.0, 125.0, 138.0, 125.0, 162.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 177.0, 176.0, 176.0, 175.0, 174.0, 181.0, 177.0, 174.0], "value": 176.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 64.0, 64.0, 75.0, 64.0, 71.0, 67.0, 70.0], "value": 67.4, "unit": "score"}]}]} 09:14:19 INFO - Return code: 0 09:14:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:14:19 INFO - # TBPL SUCCESS # 09:14:19 INFO - Running post-action listener: _package_coverage_data 09:14:19 INFO - Running post-action listener: _resource_record_post_action 09:14:19 INFO - [mozharness: 2017-09-01 16:14:19.254349Z] Finished run-tests step (success) 09:14:19 INFO - Running post-run listener: _resource_record_post_run 09:14:19 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 5550080; Write bytes: 391749632; Read time: 5992; Write time: 394324 09:14:19 INFO - TinderboxPrint: CPU usage
13.3% 09:14:19 INFO - TinderboxPrint: I/O read bytes / time
5,550,080 / 5,992 09:14:19 INFO - TinderboxPrint: I/O write bytes / time
391,749,632 / 394,324 09:14:19 INFO - TinderboxPrint: CPU idle
8,271.6 (86.7%) 09:14:19 INFO - TinderboxPrint: CPU user
1,202.5 (12.6%) 09:14:19 INFO - TinderboxPrint: Swap in / out
0 / 0 09:14:19 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106369024; Read time: 0; Write time: 177924 09:14:19 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:14:19 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 5545984; Write bytes: 284971008; Read time: 5980; Write time: 216372 09:14:19 INFO - Running post-run listener: _upload_blobber_files 09:14:19 INFO - Blob upload gear active. 09:14:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:14:19 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 09:14:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:14:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:14:20 INFO - (blobuploader) - INFO - Open directory for files ... 09:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:21 INFO - (blobuploader) - INFO - Done attempting. 09:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:14:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:24 INFO - (blobuploader) - INFO - Done attempting. 09:14:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:14:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:25 INFO - (blobuploader) - INFO - Done attempting. 09:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:14:26 INFO - (blobuploader) - INFO - Done attempting. 09:14:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:14:26 INFO - Return code: 0 09:14:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:14:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:14:26 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165430117ac2b26c819f920a9ed79931c9bf36d57ab7bafcfa62601c29e5d64b8669ba6bad25368be6d9ebf74a2c921fd4b55894a66cc2e2b049f92859bce753", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60dbda8ac2ed52183623e745d493eeb501a1acafdd9e6f13edd2847a7172570a6d5578a94ec8239e74751f053cb7d6f23c6f2b0529bcbb02513d95d682827caa", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866e32ec51bc3d8e67f15e4039c1299e70b660f63ee6f3ea3dfd7948a13aa973f6cedc75397812a073b3766c8e4bdfe049690068c8803ef94004c6e04370b263", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5311c5b2545dfcdb67cfd18e662071e31e2304a4ba15f6a849d09f3b82c2666cc08e463fa2f266910bfa2d287858c485c4dcb9abd64cbdc8153c2028e139969"} 09:14:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:14:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:14:26 INFO - Contents: 09:14:26 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165430117ac2b26c819f920a9ed79931c9bf36d57ab7bafcfa62601c29e5d64b8669ba6bad25368be6d9ebf74a2c921fd4b55894a66cc2e2b049f92859bce753", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60dbda8ac2ed52183623e745d493eeb501a1acafdd9e6f13edd2847a7172570a6d5578a94ec8239e74751f053cb7d6f23c6f2b0529bcbb02513d95d682827caa", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866e32ec51bc3d8e67f15e4039c1299e70b660f63ee6f3ea3dfd7948a13aa973f6cedc75397812a073b3766c8e4bdfe049690068c8803ef94004c6e04370b263", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5311c5b2545dfcdb67cfd18e662071e31e2304a4ba15f6a849d09f3b82c2666cc08e463fa2f266910bfa2d287858c485c4dcb9abd64cbdc8153c2028e139969"} 09:14:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:14:26 INFO - Copying logs to upload dir... 09:14:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:14:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.892360 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-09-01 09:14:26.804407) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:14:26.812175) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185 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/sha512/165430117ac2b26c819f920a9ed79931c9bf36d57ab7bafcfa62601c29e5d64b8669ba6bad25368be6d9ebf74a2c921fd4b55894a66cc2e2b049f92859bce753", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60dbda8ac2ed52183623e745d493eeb501a1acafdd9e6f13edd2847a7172570a6d5578a94ec8239e74751f053cb7d6f23c6f2b0529bcbb02513d95d682827caa", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866e32ec51bc3d8e67f15e4039c1299e70b660f63ee6f3ea3dfd7948a13aa973f6cedc75397812a073b3766c8e4bdfe049690068c8803ef94004c6e04370b263", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5311c5b2545dfcdb67cfd18e662071e31e2304a4ba15f6a849d09f3b82c2666cc08e463fa2f266910bfa2d287858c485c4dcb9abd64cbdc8153c2028e139969"} build_url:https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/P0kX4SRdS6ifTGg50JASWg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/165430117ac2b26c819f920a9ed79931c9bf36d57ab7bafcfa62601c29e5d64b8669ba6bad25368be6d9ebf74a2c921fd4b55894a66cc2e2b049f92859bce753", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60dbda8ac2ed52183623e745d493eeb501a1acafdd9e6f13edd2847a7172570a6d5578a94ec8239e74751f053cb7d6f23c6f2b0529bcbb02513d95d682827caa", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/866e32ec51bc3d8e67f15e4039c1299e70b660f63ee6f3ea3dfd7948a13aa973f6cedc75397812a073b3766c8e4bdfe049690068c8803ef94004c6e04370b263", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5311c5b2545dfcdb67cfd18e662071e31e2304a4ba15f6a849d09f3b82c2666cc08e463fa2f266910bfa2d287858c485c4dcb9abd64cbdc8153c2028e139969"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-01 09:14:26.847192) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:14:26.847522) ========= 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=5bd038846534b2fdfa68eeae000001e2-1504281209.302741-1508576185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004524 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-01 09:14:26.927646) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:14:26.930973) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-01 09:14:26.931295) ========= ========= Total master_lag: 0.32 =========