builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-062 starttime: 1506366626.3 results: success (0) revision: 33b7b8e81b4befcba503c0e48cd5370aeb715085 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:26.297832) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:26.298324) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:26.298650) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:26.316893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:26.317219) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-25 12:10:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.0M=0s 2017-09-25 12:10:26 (42.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.074687 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:26.409371) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:26.409727) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024674 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:26.466244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:26.466635) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 33b7b8e81b4befcba503c0e48cd5370aeb715085 --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 33b7b8e81b4befcba503c0e48cd5370aeb715085 --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=a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-25 12:10:26,526 truncating revision to first 12 chars 2017-09-25 12:10:26,526 Setting DEBUG logging. 2017-09-25 12:10:26,526 attempt 1/10 2017-09-25 12:10:26,526 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/33b7b8e81b4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-25 12:10:26,999 unpacking tar archive at: mozilla-central-33b7b8e81b4b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.791191 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:27.281634) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:27.282031) ========= script_repo_revision: 33b7b8e81b4befcba503c0e48cd5370aeb715085 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:27.282452) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:27.282752) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 12:10:27.296272) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-25 12:10:27.296619) ========= /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=a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:10:27 INFO - MultiFileLogger online at 20170925 12:10:27 in /builds/slave/test 12:10:27 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 12:10:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:10:27 INFO - {'append_to_log': False, 12:10:27 INFO - 'base_work_dir': '/builds/slave/test', 12:10:27 INFO - 'blob_upload_branch': 'Firefox', 12:10:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:10:27 INFO - 'branch': 'Firefox', 12:10:27 INFO - 'buildbot_json_path': 'buildprops.json', 12:10:27 INFO - 'code_coverage': False, 12:10:27 INFO - 'config_files': ('talos/linux_config.py',), 12:10:27 INFO - 'default_actions': ('clobber', 12:10:27 INFO - 'read-buildbot-config', 12:10:27 INFO - 'download-and-extract', 12:10:27 INFO - 'populate-webroot', 12:10:27 INFO - 'create-virtualenv', 12:10:27 INFO - 'install', 12:10:27 INFO - 'setup-mitmproxy', 12:10:27 INFO - 'run-tests'), 12:10:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:10:27 INFO - 'disable_ccov_upload': False, 12:10:27 INFO - 'disable_stylo': False, 12:10:27 INFO - 'download_minidump_stackwalk': True, 12:10:27 INFO - 'download_symbols': 'ondemand', 12:10:27 INFO - 'enable_stylo': False, 12:10:27 INFO - 'enable_webrender': False, 12:10:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:10:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:10:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:10:27 INFO - 'gecko_profile': False, 12:10:27 INFO - 'gecko_profile_interval': 0, 12:10:27 INFO - 'installer_path': 'installer.exe', 12:10:27 INFO - 'log_level': 'info', 12:10:27 INFO - 'log_name': 'talos', 12:10:27 INFO - 'log_to_console': True, 12:10:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:10:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:10:27 INFO - 'opt_config_files': (), 12:10:27 INFO - 'pip_index': False, 12:10:27 INFO - 'suite': 'dromaeojs-e10s', 12:10:27 INFO - 'system_bits': '32', 12:10:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:10:27 INFO - 'title': 'talos-linux64-ix-062', 12:10:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:10:27 INFO - 'use_talos_json': True, 12:10:27 INFO - 'verify': 'False', 12:10:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:10:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:10:27 INFO - 'work_dir': 'build'} 12:10:27 INFO - [mozharness: 2017-09-25 19:10:27.494654Z] Running clobber step. 12:10:27 INFO - Running pre-action listener: _resource_record_pre_action 12:10:27 INFO - Running main action method: clobber 12:10:27 INFO - rmtree: /builds/slave/test/build 12:10:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:10:28 INFO - Running post-action listener: _resource_record_post_action 12:10:28 INFO - [mozharness: 2017-09-25 19:10:28.595023Z] Finished clobber step (success) 12:10:28 INFO - [mozharness: 2017-09-25 19:10:28.595121Z] Running read-buildbot-config step. 12:10:28 INFO - Running pre-action listener: _resource_record_pre_action 12:10:28 INFO - Running main action method: read_buildbot_config 12:10:28 INFO - Using buildbot properties: 12:10:28 INFO - { 12:10:28 INFO - "project": "", 12:10:28 INFO - "product": "firefox", 12:10:28 INFO - "who": "ffxbld@noreply.mozilla.org", 12:10:28 INFO - "installer_path": "public/build/target.tar.bz2", 12:10:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:10:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:10:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:10:28 INFO - "stage_platform": "linux64-pgo", 12:10:28 INFO - "basedir": "/builds/slave/test", 12:10:28 INFO - "buildnumber": 204, 12:10:28 INFO - "platform": "ubuntu64_hw", 12:10:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:10:28 INFO - "slavebuilddir": "test", 12:10:28 INFO - "taskId": "bbfzTDc-Rfq3bxU66OhfmQ", 12:10:28 INFO - "branch": "mozilla-central", 12:10:28 INFO - "script_repo_revision": "production", 12:10:28 INFO - "revision": "33b7b8e81b4befcba503c0e48cd5370aeb715085", 12:10:28 INFO - "slavename": "talos-linux64-ix-062", 12:10:28 INFO - "repo_path": "mozilla-central" 12:10:28 INFO - } 12:10:28 INFO - Finding installer, test and symbols from parent task. 12:10:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bbfzTDc-Rfq3bxU66OhfmQ'}, attempt #1 12:10:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bbfzTDc-Rfq3bxU66OhfmQ'}, attempt #1 12:10:29 INFO - Task dependencies: NWLEEd7oRfGpMvp2N6mJHQ 12:10:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ'}, attempt #1 12:10:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.tar.bz2 12:10:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.test_packages.json 12:10:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.crashreporter-symbols.zip 12:10:29 INFO - Running post-action listener: _resource_record_post_action 12:10:29 INFO - [mozharness: 2017-09-25 19:10:29.964448Z] Finished read-buildbot-config step (success) 12:10:29 INFO - [mozharness: 2017-09-25 19:10:29.964668Z] Running download-and-extract step. 12:10:29 INFO - Running pre-action listener: _resource_record_pre_action 12:10:29 INFO - Running main action method: download_and_extract 12:10:29 INFO - mkdir: /builds/slave/test/build/tests 12:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:29 INFO - trying https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.test_packages.json 12:10:29 INFO - Downloading https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:10:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:10:30 INFO - Downloaded 989 bytes. 12:10:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:10:30 INFO - Using the following test package requirements: 12:10:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:10:30 INFO - u'common': [u'target.common.tests.zip'], 12:10:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:10:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:10:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:10:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:10:30 INFO - u'mozbase': [u'target.common.tests.zip'], 12:10:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:10:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:10:30 INFO - u'web-platform': [u'target.common.tests.zip', 12:10:30 INFO - u'target.web-platform.tests.tar.gz'], 12:10:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:10:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:10:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.common.tests.zip 12:10:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:10:30 INFO - Fetch https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.common.tests.zip into memory 12:10:31 INFO - Content-Length response header: 55360545 12:10:31 INFO - Bytes received: 55360545 12:10:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.talos.tests.zip 12:10:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:10:42 INFO - Fetch https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.talos.tests.zip into memory 12:10:42 INFO - Content-Length response header: 14513823 12:10:42 INFO - Bytes received: 14513823 12:10:43 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')]} 12:10:43 INFO - trying https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.tar.bz2 12:10:43 INFO - Downloading https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:10:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:10:44 INFO - Downloaded 62345657 bytes. 12:10:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.tar.bz2 12:10:44 INFO - mkdir: /builds/slave/test/properties 12:10:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:10:44 INFO - Writing to file /builds/slave/test/properties/build_url 12:10:44 INFO - Contents: 12:10:44 INFO - build_url:https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.tar.bz2 12:10:44 INFO - Running post-action listener: _resource_record_post_action 12:10:44 INFO - Running post-action listener: find_tests_for_verification 12:10:44 INFO - Running post-action listener: set_extra_try_arguments 12:10:44 INFO - [mozharness: 2017-09-25 19:10:44.368586Z] Finished download-and-extract step (success) 12:10:44 INFO - [mozharness: 2017-09-25 19:10:44.368851Z] Running populate-webroot step. 12:10:44 INFO - Running pre-action listener: _resource_record_pre_action 12:10:44 INFO - Running main action method: populate_webroot 12:10:44 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:10:44 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['tresize', 12:10:44 INFO - 'tcanvasmark']}, 12:10:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:10:44 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['dromaeo_css', 12:10:44 INFO - 'kraken']}, 12:10:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:10:44 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:10:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'tests': ['damp', 'tps']}, 12:10:44 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['damp', 'tps']}, 12:10:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:10:44 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['dromaeo_dom']}, 12:10:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:10:44 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['basic_compositor_video', 12:10:44 INFO - 'glvideo']}, 12:10:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:10:44 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['ts_paint_webext', 12:10:44 INFO - 'tp5o_webext']}, 12:10:44 INFO - 'other-e10s': {'tests': ['a11yr', 12:10:44 INFO - 'ts_paint', 12:10:44 INFO - 'tpaint', 12:10:44 INFO - 'sessionrestore', 12:10:44 INFO - 'sessionrestore_many_windows', 12:10:44 INFO - 'sessionrestore_no_auto_restore', 12:10:44 INFO - 'tabpaint']}, 12:10:44 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['a11yr', 12:10:44 INFO - 'ts_paint', 12:10:44 INFO - 'tpaint', 12:10:44 INFO - 'sessionrestore', 12:10:44 INFO - 'sessionrestore_many_windows', 12:10:44 INFO - 'sessionrestore_no_auto_restore', 12:10:44 INFO - 'tabpaint']}, 12:10:44 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:10:44 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:10:44 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['perf_reftest_singletons']}, 12:10:44 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['perf_reftest']}, 12:10:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:10:44 INFO - 'tsvgr_opacity', 12:10:44 INFO - 'tart', 12:10:44 INFO - 'tscrollx', 12:10:44 INFO - 'tsvg_static']}, 12:10:44 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['tsvgx', 12:10:44 INFO - 'tsvgr_opacity', 12:10:44 INFO - 'tart', 12:10:44 INFO - 'tscrollx', 12:10:44 INFO - 'tsvg_static']}, 12:10:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:10:44 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo'], 12:10:44 INFO - 'tests': ['tp5o']}, 12:10:44 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:10:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:10:44 INFO - 'talos_options': ['--mitmproxy', 12:10:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:10:44 INFO - '--firstNonBlankPaint'], 12:10:44 INFO - 'tests': ['tp6_google', 12:10:44 INFO - 'tp6_youtube', 12:10:44 INFO - 'tp6_amazon', 12:10:44 INFO - 'tp6_facebook']}, 12:10:44 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:10:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:10:44 INFO - 'talos_options': ['--disable-stylo', 12:10:44 INFO - '--mitmproxy', 12:10:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:10:44 INFO - '--firstNonBlankPaint'], 12:10:44 INFO - 'tests': ['tp6_google', 12:10:44 INFO - 'tp6_youtube', 12:10:44 INFO - 'tp6_amazon', 12:10:44 INFO - 'tp6_facebook']}, 12:10:44 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:10:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:10:44 INFO - 'talos_options': ['--stylo-threads=1', 12:10:44 INFO - '--mitmproxy', 12:10:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:10:44 INFO - '--firstNonBlankPaint'], 12:10:44 INFO - 'tests': ['tp6_google', 12:10:44 INFO - 'tp6_youtube', 12:10:44 INFO - 'tp6_amazon', 12:10:44 INFO - 'tp6_facebook']}, 12:10:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--xperf_path', 12:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:10:44 INFO - 'tests': ['tp5n']}, 12:10:44 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:10:44 INFO - 'talos_options': ['--disable-stylo', 12:10:44 INFO - '--xperf_path', 12:10:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:10:44 INFO - 'tests': ['tp5n']}}} 12:10:44 INFO - Running post-action listener: _resource_record_post_action 12:10:44 INFO - [mozharness: 2017-09-25 19:10:44.514670Z] Finished populate-webroot step (success) 12:10:44 INFO - [mozharness: 2017-09-25 19:10:44.514768Z] Running create-virtualenv step. 12:10:44 INFO - Running pre-action listener: _resource_record_pre_action 12:10:44 INFO - Running main action method: create_virtualenv 12:10:44 INFO - Creating virtualenv /builds/slave/test/build/venv 12:10:44 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:10:44 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:10:44 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:10:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:10:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:10:45 INFO - Installing distribute.............................................................................................................................................................................................done. 12:10:45 INFO - Installing pip...............done. 12:10:45 INFO - Return code: 0 12:10:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:10:45 INFO - Reading from file tmpfile_stdout 12:10:45 INFO - Output received: 12:10:45 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:10:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:45 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:10:45 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 12:10:45 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 12:10:45 INFO - Using env: {'DISPLAY': ':0', 12:10:45 INFO - 'HOME': '/home/cltbld', 12:10:45 INFO - 'LANG': 'en_US.UTF-8', 12:10:45 INFO - 'LANGUAGE': 'en_US:en', 12:10:45 INFO - 'LOGNAME': 'cltbld', 12:10:45 INFO - 'MAIL': '/var/mail/cltbld', 12:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:45 INFO - 'MOZ_NO_REMOTE': '1', 12:10:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:45 INFO - 'NO_EM_RESTART': '1', 12:10:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:45 INFO - 'PWD': '/builds/slave/test', 12:10:45 INFO - 'SHELL': '/bin/bash', 12:10:45 INFO - 'SHLVL': '1', 12:10:45 INFO - 'TERM': 'linux', 12:10:45 INFO - 'TMOUT': '86400', 12:10:45 INFO - 'USER': 'cltbld', 12:10:45 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:45 INFO - '_': '/tools/buildbot/bin/python'} 12:10:46 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:10:46 INFO - Downloading/unpacking pip>=1.5 12:10:46 INFO - Running setup.py egg_info for package pip 12:10:46 INFO - warning: no files found matching 'pip/cacert.pem' 12:10:46 INFO - warning: no files found matching '*.html' under directory 'docs' 12:10:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:10:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:10:46 INFO - Installing collected packages: pip 12:10:46 INFO - Found existing installation: pip 1.1 12:10:46 INFO - Uninstalling pip: 12:10:46 INFO - Successfully uninstalled pip 12:10:46 INFO - Running setup.py install for pip 12:10:46 INFO - warning: no files found matching 'pip/cacert.pem' 12:10:46 INFO - warning: no files found matching '*.html' under directory 'docs' 12:10:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:10:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:10:46 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:10:46 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:10:46 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:10:46 INFO - Successfully installed pip 12:10:46 INFO - Cleaning up... 12:10:46 INFO - Return code: 0 12:10:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:46 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:10:46 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 12:10:46 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 12:10:46 INFO - Using env: {'DISPLAY': ':0', 12:10:46 INFO - 'HOME': '/home/cltbld', 12:10:46 INFO - 'LANG': 'en_US.UTF-8', 12:10:46 INFO - 'LANGUAGE': 'en_US:en', 12:10:46 INFO - 'LOGNAME': 'cltbld', 12:10:46 INFO - 'MAIL': '/var/mail/cltbld', 12:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:46 INFO - 'MOZ_NO_REMOTE': '1', 12:10:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:46 INFO - 'NO_EM_RESTART': '1', 12:10:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:46 INFO - 'PWD': '/builds/slave/test', 12:10:46 INFO - 'SHELL': '/bin/bash', 12:10:46 INFO - 'SHLVL': '1', 12:10:46 INFO - 'TERM': 'linux', 12:10:46 INFO - 'TMOUT': '86400', 12:10:46 INFO - 'USER': 'cltbld', 12:10:46 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:46 INFO - '_': '/tools/buildbot/bin/python'} 12:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:46 INFO - Downloading/unpacking psutil>=3.1.1 12:10:46 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 12:10:46 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 12:10:46 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 12:10:46 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 12:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:10:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:10:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:10:48 INFO - Installing collected packages: psutil 12:10:48 INFO - Running setup.py install for psutil 12:10:48 INFO - building 'psutil._psutil_linux' extension 12:10:48 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 12:10:48 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 12:10:48 INFO - building 'psutil._psutil_posix' extension 12:10:48 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 12:10:48 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 12:10:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:10:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:10:48 INFO - Successfully installed psutil 12:10:48 INFO - Cleaning up... 12:10:48 INFO - Return code: 0 12:10:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:10:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:10:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:10:48 INFO - Using env: {'DISPLAY': ':0', 12:10:48 INFO - 'HOME': '/home/cltbld', 12:10:48 INFO - 'LANG': 'en_US.UTF-8', 12:10:48 INFO - 'LANGUAGE': 'en_US:en', 12:10:48 INFO - 'LOGNAME': 'cltbld', 12:10:48 INFO - 'MAIL': '/var/mail/cltbld', 12:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:48 INFO - 'MOZ_NO_REMOTE': '1', 12:10:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:48 INFO - 'NO_EM_RESTART': '1', 12:10:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:48 INFO - 'PWD': '/builds/slave/test', 12:10:48 INFO - 'SHELL': '/bin/bash', 12:10:48 INFO - 'SHLVL': '1', 12:10:48 INFO - 'TERM': 'linux', 12:10:48 INFO - 'TMOUT': '86400', 12:10:48 INFO - 'USER': 'cltbld', 12:10:48 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:48 INFO - '_': '/tools/buildbot/bin/python'} 12:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:10:48 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 12:10:48 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 12:10:48 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 12:10:48 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 12:10:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:10:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:10:50 INFO - Installing collected packages: mozsystemmonitor 12:10:50 INFO - Running setup.py install for mozsystemmonitor 12:10:50 INFO - Successfully installed mozsystemmonitor 12:10:50 INFO - Cleaning up... 12:10:51 INFO - Return code: 0 12:10:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:10:51 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')]} 12:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:51 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')]} 12:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:51 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:10:51 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 12:10:51 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 12:10:51 INFO - Using env: {'DISPLAY': ':0', 12:10:51 INFO - 'HOME': '/home/cltbld', 12:10:51 INFO - 'LANG': 'en_US.UTF-8', 12:10:51 INFO - 'LANGUAGE': 'en_US:en', 12:10:51 INFO - 'LOGNAME': 'cltbld', 12:10:51 INFO - 'MAIL': '/var/mail/cltbld', 12:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:51 INFO - 'MOZ_NO_REMOTE': '1', 12:10:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:51 INFO - 'NO_EM_RESTART': '1', 12:10:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:51 INFO - 'PWD': '/builds/slave/test', 12:10:51 INFO - 'SHELL': '/bin/bash', 12:10:51 INFO - 'SHLVL': '1', 12:10:51 INFO - 'TERM': 'linux', 12:10:51 INFO - 'TMOUT': '86400', 12:10:51 INFO - 'USER': 'cltbld', 12:10:51 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:51 INFO - '_': '/tools/buildbot/bin/python'} 12:10:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:51 INFO - Downloading/unpacking jsonschema==2.5.1 12:10:51 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 12:10:51 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 12:10:51 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 12:10:51 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 12:10:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:10:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:52 INFO - Downloading functools32-3.2.3-2.tar.gz 12:10:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:10:52 INFO - warning: no files found matching '*.txt' 12:10:52 INFO - no previously-included directories found matching 'build' 12:10:52 INFO - no previously-included directories found matching 'dist' 12:10:52 INFO - no previously-included directories found matching '.git*' 12:10:52 INFO - Installing collected packages: jsonschema, functools32 12:10:52 INFO - Running setup.py install for functools32 12:10:53 INFO - warning: no files found matching '*.txt' 12:10:53 INFO - no previously-included directories found matching 'build' 12:10:53 INFO - no previously-included directories found matching 'dist' 12:10:53 INFO - no previously-included directories found matching '.git*' 12:10:53 INFO - Successfully installed jsonschema functools32 12:10:53 INFO - Cleaning up... 12:10:53 INFO - Return code: 0 12:10:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:53 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:10:53 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 12:10:53 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 12:10:53 INFO - Using env: {'DISPLAY': ':0', 12:10:53 INFO - 'HOME': '/home/cltbld', 12:10:53 INFO - 'LANG': 'en_US.UTF-8', 12:10:53 INFO - 'LANGUAGE': 'en_US:en', 12:10:53 INFO - 'LOGNAME': 'cltbld', 12:10:53 INFO - 'MAIL': '/var/mail/cltbld', 12:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:53 INFO - 'MOZ_NO_REMOTE': '1', 12:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:53 INFO - 'NO_EM_RESTART': '1', 12:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:53 INFO - 'PWD': '/builds/slave/test', 12:10:53 INFO - 'SHELL': '/bin/bash', 12:10:53 INFO - 'SHLVL': '1', 12:10:53 INFO - 'TERM': 'linux', 12:10:53 INFO - 'TMOUT': '86400', 12:10:53 INFO - 'USER': 'cltbld', 12:10:53 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:53 INFO - '_': '/tools/buildbot/bin/python'} 12:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:10:53 INFO - Cleaning up... 12:10:53 INFO - Return code: 0 12:10:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:53 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:10:53 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 12:10:53 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 12:10:53 INFO - Using env: {'DISPLAY': ':0', 12:10:53 INFO - 'HOME': '/home/cltbld', 12:10:53 INFO - 'LANG': 'en_US.UTF-8', 12:10:53 INFO - 'LANGUAGE': 'en_US:en', 12:10:53 INFO - 'LOGNAME': 'cltbld', 12:10:53 INFO - 'MAIL': '/var/mail/cltbld', 12:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:53 INFO - 'MOZ_NO_REMOTE': '1', 12:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:53 INFO - 'NO_EM_RESTART': '1', 12:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:53 INFO - 'PWD': '/builds/slave/test', 12:10:53 INFO - 'SHELL': '/bin/bash', 12:10:53 INFO - 'SHLVL': '1', 12:10:53 INFO - 'TERM': 'linux', 12:10:53 INFO - 'TMOUT': '86400', 12:10:53 INFO - 'USER': 'cltbld', 12:10:53 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:53 INFO - '_': '/tools/buildbot/bin/python'} 12:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:54 INFO - Downloading/unpacking blobuploader==1.2.4 12:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - Downloading blobuploader-1.2.4.tar.gz 12:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:10:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:10:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:10:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:10:55 INFO - Downloading docopt-0.6.1.tar.gz 12:10:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:10:55 INFO - Installing collected packages: blobuploader, requests, docopt 12:10:55 INFO - Running setup.py install for blobuploader 12:10:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:10:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:10:55 INFO - Running setup.py install for requests 12:10:56 INFO - Running setup.py install for docopt 12:10:56 INFO - Successfully installed blobuploader requests docopt 12:10:56 INFO - Cleaning up... 12:10:56 INFO - Return code: 0 12:10:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:56 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:10:56 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 12:10:56 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 12:10:56 INFO - Using env: {'DISPLAY': ':0', 12:10:56 INFO - 'HOME': '/home/cltbld', 12:10:56 INFO - 'LANG': 'en_US.UTF-8', 12:10:56 INFO - 'LANGUAGE': 'en_US:en', 12:10:56 INFO - 'LOGNAME': 'cltbld', 12:10:56 INFO - 'MAIL': '/var/mail/cltbld', 12:10:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:56 INFO - 'MOZ_NO_REMOTE': '1', 12:10:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:56 INFO - 'NO_EM_RESTART': '1', 12:10:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:56 INFO - 'PWD': '/builds/slave/test', 12:10:56 INFO - 'SHELL': '/bin/bash', 12:10:56 INFO - 'SHLVL': '1', 12:10:56 INFO - 'TERM': 'linux', 12:10:56 INFO - 'TMOUT': '86400', 12:10:56 INFO - 'USER': 'cltbld', 12:10:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:10:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:56 INFO - '_': '/tools/buildbot/bin/python'} 12:10:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:10:56 INFO - Running setup.py (path:/tmp/pip-Yo9InB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:10:56 INFO - Running setup.py (path:/tmp/pip-GpKLhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:10:56 INFO - Running setup.py (path:/tmp/pip-WpRmOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:10:56 INFO - Running setup.py (path:/tmp/pip-AcDRnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:10:56 INFO - Running setup.py (path:/tmp/pip-GRTE75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:10:56 INFO - Running setup.py (path:/tmp/pip-GjFSVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:10:56 INFO - Running setup.py (path:/tmp/pip-bN1SdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:10:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:10:57 INFO - Running setup.py (path:/tmp/pip-YZeswU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:10:57 INFO - Running setup.py (path:/tmp/pip-qhA5M_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:10:57 INFO - Running setup.py (path:/tmp/pip-gnr6nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:10:57 INFO - Running setup.py (path:/tmp/pip-mGacxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:10:57 INFO - Running setup.py (path:/tmp/pip-HkB88G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:10:57 INFO - Running setup.py (path:/tmp/pip-UuEOWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:10:57 INFO - Running setup.py (path:/tmp/pip-7eXThK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:57 INFO - Running setup.py (path:/tmp/pip-I7pnlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:10:57 INFO - Running setup.py (path:/tmp/pip-rtVClb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:10:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:10:57 INFO - Running setup.py (path:/tmp/pip-V0Wi4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:10:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:10:57 INFO - Running setup.py install for manifestparser 12:10:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:10:57 INFO - Running setup.py install for mozcrash 12:10:57 INFO - Running setup.py install for mozdebug 12:10:58 INFO - Running setup.py install for mozdevice 12:10:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozfile 12:10:58 INFO - Running setup.py install for mozhttpd 12:10:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozinfo 12:10:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozInstall 12:10:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozleak 12:10:58 INFO - Running setup.py install for mozlog 12:10:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for moznetwork 12:10:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Running setup.py install for mozprocess 12:10:58 INFO - Running setup.py install for mozprofile 12:10:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Running setup.py install for mozrunner 12:10:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Running setup.py install for mozscreenshot 12:10:59 INFO - Running setup.py install for moztest 12:10:59 INFO - Running setup.py install for mozversion 12:10:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:10:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:10:59 INFO - Cleaning up... 12:10:59 INFO - Return code: 0 12:10:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:59 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:10:59 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 12:10:59 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 12:10:59 INFO - Using env: {'DISPLAY': ':0', 12:10:59 INFO - 'HOME': '/home/cltbld', 12:10:59 INFO - 'LANG': 'en_US.UTF-8', 12:10:59 INFO - 'LANGUAGE': 'en_US:en', 12:10:59 INFO - 'LOGNAME': 'cltbld', 12:10:59 INFO - 'MAIL': '/var/mail/cltbld', 12:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:59 INFO - 'MOZ_NO_REMOTE': '1', 12:10:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:10:59 INFO - 'NO_EM_RESTART': '1', 12:10:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:59 INFO - 'PWD': '/builds/slave/test', 12:10:59 INFO - 'SHELL': '/bin/bash', 12:10:59 INFO - 'SHLVL': '1', 12:10:59 INFO - 'TERM': 'linux', 12:10:59 INFO - 'TMOUT': '86400', 12:10:59 INFO - 'USER': 'cltbld', 12:10:59 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:59 INFO - '_': '/tools/buildbot/bin/python'} 12:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:10:59 INFO - Running setup.py (path:/tmp/pip-l8ASnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:10:59 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)) 12:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:10:59 INFO - Running setup.py (path:/tmp/pip-Ad0o_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:10:59 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)) 12:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:10:59 INFO - Running setup.py (path:/tmp/pip-OA7TwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:10:59 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)) 12:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:10:59 INFO - Running setup.py (path:/tmp/pip-Q3ZDzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:11:00 INFO - Running setup.py (path:/tmp/pip-HZ2E9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:11:00 INFO - Running setup.py (path:/tmp/pip-WzpX9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:11:00 INFO - Running setup.py (path:/tmp/pip-_IjYiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:11:00 INFO - Running setup.py (path:/tmp/pip-GqY7y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:11:00 INFO - Running setup.py (path:/tmp/pip-H0faGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:11:00 INFO - Running setup.py (path:/tmp/pip-dOYhhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:11:00 INFO - Running setup.py (path:/tmp/pip-xO6yIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:11:00 INFO - Running setup.py (path:/tmp/pip-i7Me0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:11:00 INFO - Running setup.py (path:/tmp/pip-2VpvNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:00 INFO - Running setup.py (path:/tmp/pip-qKls3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:00 INFO - Running setup.py (path:/tmp/pip-gA3X6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:00 INFO - Running setup.py (path:/tmp/pip-xoyqit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:00 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)) 12:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:00 INFO - Running setup.py (path:/tmp/pip-oiZk4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 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)) 12:11:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:00 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 12:11:00 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 12:11:00 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 12:11:00 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 12:11:02 INFO - Downloading blessings-1.6.tar.gz 12:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:02 INFO - Installing collected packages: blessings 12:11:02 INFO - Running setup.py install for blessings 12:11:02 INFO - Successfully installed blessings 12:11:02 INFO - Cleaning up... 12:11:02 INFO - Return code: 0 12:11:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:11:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:02 INFO - Reading from file tmpfile_stdout 12:11:02 INFO - Current package versions: 12:11:02 INFO - argparse == 1.2.1 12:11:02 INFO - blessings == 1.6 12:11:02 INFO - blobuploader == 1.2.4 12:11:02 INFO - docopt == 0.6.1 12:11:02 INFO - functools32 == 3.2.3-2 12:11:02 INFO - jsonschema == 2.5.1 12:11:02 INFO - manifestparser == 1.1 12:11:02 INFO - mozInstall == 1.13 12:11:02 INFO - mozcrash == 1.0 12:11:02 INFO - mozdebug == 0.1 12:11:02 INFO - mozdevice == 0.51 12:11:02 INFO - mozfile == 1.2 12:11:02 INFO - mozhttpd == 0.7 12:11:02 INFO - mozinfo == 0.10 12:11:02 INFO - mozleak == 0.1 12:11:02 INFO - mozlog == 3.5 12:11:02 INFO - moznetwork == 0.27 12:11:02 INFO - mozprocess == 0.25 12:11:02 INFO - mozprofile == 0.28 12:11:02 INFO - mozrunner == 6.13 12:11:02 INFO - mozscreenshot == 0.1 12:11:02 INFO - mozsystemmonitor == 0.3 12:11:02 INFO - moztest == 0.8 12:11:02 INFO - mozversion == 1.4 12:11:02 INFO - psutil == 3.1.1 12:11:02 INFO - requests == 1.2.3 12:11:02 INFO - wsgiref == 0.1.2 12:11:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:02 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 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:11:02 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 12:11:02 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 12:11:02 INFO - Using env: {'DISPLAY': ':0', 12:11:02 INFO - 'HOME': '/home/cltbld', 12:11:02 INFO - 'LANG': 'en_US.UTF-8', 12:11:02 INFO - 'LANGUAGE': 'en_US:en', 12:11:02 INFO - 'LOGNAME': 'cltbld', 12:11:02 INFO - 'MAIL': '/var/mail/cltbld', 12:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:02 INFO - 'MOZ_NO_REMOTE': '1', 12:11:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:02 INFO - 'NO_EM_RESTART': '1', 12:11:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:02 INFO - 'PWD': '/builds/slave/test', 12:11:02 INFO - 'SHELL': '/bin/bash', 12:11:02 INFO - 'SHLVL': '1', 12:11:02 INFO - 'TERM': 'linux', 12:11:02 INFO - 'TMOUT': '86400', 12:11:02 INFO - 'USER': 'cltbld', 12:11:02 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:02 INFO - '_': '/tools/buildbot/bin/python'} 12:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 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)) 12:11:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:11:02 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 12:11:02 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 12:11:02 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 12:11:02 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 12:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:11:03 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)) 12:11:03 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)) 12:11:03 INFO - Installing collected packages: simplejson 12:11:03 INFO - Running setup.py install for simplejson 12:11:04 INFO - building 'simplejson._speedups' extension 12:11:04 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 12:11:04 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 12:11:04 INFO - Successfully installed simplejson 12:11:04 INFO - Cleaning up... 12:11:04 INFO - Return code: 0 12:11:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:11:04 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')]} 12:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:04 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')]} 12:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26e9b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x288e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', '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 12:11: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', 'jsonschema'] in /builds/slave/test/build 12:11: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 jsonschema 12:11:05 INFO - Using env: {'DISPLAY': ':0', 12:11:05 INFO - 'HOME': '/home/cltbld', 12:11:05 INFO - 'LANG': 'en_US.UTF-8', 12:11:05 INFO - 'LANGUAGE': 'en_US:en', 12:11:05 INFO - 'LOGNAME': 'cltbld', 12:11:05 INFO - 'MAIL': '/var/mail/cltbld', 12:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:05 INFO - 'MOZ_NO_REMOTE': '1', 12:11:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:05 INFO - 'NO_EM_RESTART': '1', 12:11:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:05 INFO - 'PWD': '/builds/slave/test', 12:11:05 INFO - 'SHELL': '/bin/bash', 12:11:05 INFO - 'SHLVL': '1', 12:11:05 INFO - 'TERM': 'linux', 12:11:05 INFO - 'TMOUT': '86400', 12:11:05 INFO - 'USER': 'cltbld', 12:11:05 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:05 INFO - '_': '/tools/buildbot/bin/python'} 12:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:11:05 INFO - Cleaning up... 12:11:05 INFO - Return code: 0 12:11:05 INFO - Running post-action listener: _resource_record_post_action 12:11:05 INFO - Running post-action listener: _start_resource_monitoring 12:11:05 INFO - Starting resource monitoring. 12:11:05 INFO - [mozharness: 2017-09-25 19:11:05.265784Z] Finished create-virtualenv step (success) 12:11:05 INFO - [mozharness: 2017-09-25 19:11:05.266394Z] Running install step. 12:11:05 INFO - Running pre-action listener: _resource_record_pre_action 12:11:05 INFO - Running main action method: install 12:11:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:05 INFO - Reading from file tmpfile_stdout 12:11:05 INFO - Detecting whether we're running mozinstall >=1.0... 12:11:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:11:05 INFO - Reading from file tmpfile_stdout 12:11:05 INFO - Output received: 12:11:05 INFO - Usage: mozinstall [options] installer 12:11:05 INFO - Options: 12:11:05 INFO - -h, --help show this help message and exit 12:11:05 INFO - -d DEST, --destination=DEST 12:11:05 INFO - Directory to install application into. [default: 12:11:05 INFO - "/builds/slave/test"] 12:11:05 INFO - --app=APP Application being installed. [default: firefox] 12:11:05 INFO - mkdir: /builds/slave/test/build/application 12:11:05 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'] 12:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:11:23 INFO - Reading from file tmpfile_stdout 12:11:23 INFO - Output received: 12:11:23 INFO - /builds/slave/test/build/application/firefox/firefox 12:11:23 INFO - Running post-action listener: _resource_record_post_action 12:11:23 INFO - [mozharness: 2017-09-25 19:11:23.235421Z] Finished install step (success) 12:11:23 INFO - [mozharness: 2017-09-25 19:11:23.235675Z] Running setup-mitmproxy step. 12:11:23 INFO - Running pre-action listener: _resource_record_pre_action 12:11:23 INFO - Running main action method: setup_mitmproxy 12:11:23 INFO - Skipping: mitmproxy is not required 12:11:23 INFO - Running post-action listener: _resource_record_post_action 12:11:23 INFO - [mozharness: 2017-09-25 19:11:23.236860Z] Finished setup-mitmproxy step (success) 12:11:23 INFO - [mozharness: 2017-09-25 19:11:23.237085Z] Running run-tests step. 12:11:23 INFO - Running pre-action listener: _resource_record_pre_action 12:11:23 INFO - Running pre-action listener: _set_gcov_prefix 12:11:23 INFO - Running main action method: run_tests 12:11:23 WARNING - Try message not found. 12:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:11:23 INFO - Python 2.7.3 12:11:23 INFO - Return code: 0 12:11:23 INFO - grabbing minidump binary from tooltool 12:11:23 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')]} 12:11:23 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 0x288bd60>, '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 0x288ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x288cef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:11:23 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 12:11:23 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 12:11:23 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 12:11:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:11:23 INFO - Return code: 0 12:11:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:11:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:11:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:11:23 INFO - ENV: RUST_BACKTRACE is now full 12:11:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:11:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:11:23 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/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 12:11:23 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/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 12:11:23 INFO - Using env: {'DISPLAY': ':0', 12:11:23 INFO - 'HOME': '/home/cltbld', 12:11:23 INFO - 'LANG': 'en_US.UTF-8', 12:11:23 INFO - 'LANGUAGE': 'en_US:en', 12:11:23 INFO - 'LOGNAME': 'cltbld', 12:11:23 INFO - 'MAIL': '/var/mail/cltbld', 12:11:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:11:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:23 INFO - 'MOZ_NO_REMOTE': '1', 12:11:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:11:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:23 INFO - 'NO_EM_RESTART': '1', 12:11:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:23 INFO - 'PWD': '/builds/slave/test', 12:11:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:11:23 INFO - 'RUST_BACKTRACE': 'full', 12:11:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:11:23 INFO - 'SHELL': '/bin/bash', 12:11:23 INFO - 'SHLVL': '1', 12:11:23 INFO - 'TERM': 'linux', 12:11:23 INFO - 'TMOUT': '86400', 12:11:23 INFO - 'USER': 'cltbld', 12:11:23 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:23 INFO - '_': '/tools/buildbot/bin/python'} 12:11:23 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/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 12:11:23 INFO - mozversion application_buildid: 20170925174106 12:11:23 INFO - mozversion application_changeset: 33b7b8e81b4befcba503c0e48cd5370aeb715085 12:11:23 INFO - mozversion application_display_name: Nightly 12:11:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:11:23 INFO - mozversion application_name: Firefox 12:11:23 INFO - mozversion application_remotingname: firefox 12:11:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:11:23 INFO - mozversion application_vendor: Mozilla 12:11:23 INFO - mozversion application_version: 58.0a1 12:11:23 INFO - mozversion platform_buildid: 20170925174106 12:11:23 INFO - mozversion platform_changeset: 33b7b8e81b4befcba503c0e48cd5370aeb715085 12:11:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:11:23 INFO - mozversion platform_version: 58.0a1 12:11:23 INFO - using testdate: 1506366683 12:11:23 INFO - actual date: 1506366683 12:11:23 INFO - starting webserver on 'localhost:55324' 12:11:23 INFO - SUITE-START | Running 2 tests 12:11:23 INFO - TEST-START | dromaeo_css 12:11:23 INFO - Initialising browser for dromaeo_css test... 12:11:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55324/getInfo.html -profile /tmp/tmpOxD_10/profile 12:11:23 INFO - TEST-INFO | started process 17249 (/builds/slave/test/build/application/firefox/firefox http://localhost:55324/getInfo.html) 12:11:29 INFO - TEST-INFO | 17249: exit 0 12:11:29 INFO - Browser initialized. 12:11:29 INFO - Running cycle 1/1 for dromaeo_css test... 12:11:29 INFO - Using env: {'DISPLAY': ':0', 12:11:29 INFO - 'HOME': '/home/cltbld', 12:11:29 INFO - 'JSGC_DISABLE_POISONING': '1', 12:11:29 INFO - 'LANG': 'en_US.UTF-8', 12:11:29 INFO - 'LANGUAGE': 'en_US:en', 12:11:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:11:29 INFO - 'LOGNAME': 'cltbld', 12:11:29 INFO - 'MAIL': '/var/mail/cltbld', 12:11:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:11:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:11:29 INFO - 'MOZ_CRASHREPORTER': '1', 12:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:11:29 INFO - 'MOZ_NO_REMOTE': '1', 12:11:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:11:29 INFO - 'NO_EM_RESTART': '1', 12:11:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:29 INFO - 'PWD': '/builds/slave/test', 12:11:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:11:29 INFO - 'RUST_BACKTRACE': 'full', 12:11:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:11:29 INFO - 'SHELL': '/bin/bash', 12:11:29 INFO - 'SHLVL': '1', 12:11:29 INFO - 'TERM': 'linux', 12:11:29 INFO - 'TMOUT': '86400', 12:11:29 INFO - 'USER': 'cltbld', 12:11:29 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:29 INFO - '_': '/tools/buildbot/bin/python'} 12:11:29 INFO - TEST-INFO | started process 17489 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOxD_10/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:30 INFO - PID 17489 | 12:11:30 INFO - PID 17489 | (/builds/slave/test/build/application/firefox/firefox:17539): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:11:30 INFO - PID 17489 | 12:11:40 INFO - PID 17489 | RSS: Main: 145063936 12:11:40 INFO - PID 17489 | 12:14:38 INFO - PID 17489 | Cycle 1(1): loaded http://localhost:55324/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55324/tests/dromaeo/cssquery-ext.html) 12:14:39 INFO - PID 17489 | RSS: Main: 146214912 12:14:39 INFO - PID 17489 | 12:17:41 INFO - PID 17489 | Cycle 1(1): loaded http://localhost:55324/tests/dromaeo/cssquery-ext.html (next: http://localhost:55324/tests/dromaeo/cssquery-jquery.html) 12:17:41 INFO - PID 17489 | RSS: Main: 146333696 12:17:41 INFO - PID 17489 | 12:20:43 INFO - PID 17489 | Cycle 1(1): loaded http://localhost:55324/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55324/tests/dromaeo/cssquery-mootools.html) 12:20:44 INFO - PID 17489 | RSS: Main: 148586496 12:20:44 INFO - PID 17489 | 12:23:45 INFO - PID 17489 | Cycle 1(1): loaded http://localhost:55324/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55324/tests/dromaeo/cssquery-prototype.html) 12:23:46 INFO - PID 17489 | RSS: Main: 147156992 12:23:46 INFO - PID 17489 | 12:26:49 INFO - PID 17489 | Cycle 1(1): loaded http://localhost:55324/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55324/tests/dromaeo/cssquery-yui.html) 12:26:50 INFO - PID 17489 | RSS: Main: 148303872 12:26:50 INFO - PID 17489 | 12:29:51 INFO - PID 17489 | Cycle 1(1): loaded http://localhost:55324/tests/dromaeo/cssquery-yui.html (next: http://localhost:55324/tests/dromaeo/cssquery-dojo.html) 12:29:52 INFO - PID 17489 | RSS: Main: 150478848 12:29:52 INFO - PID 17489 | 12:29:52 INFO - PID 17489 | __start_tp_report 12:29:52 INFO - PID 17489 | _x_x_mozilla_page_load 12:29:52 INFO - PID 17489 | _x_x_mozilla_page_load_details 12:29:52 INFO - PID 17489 | |i|pagename|runs| 12:29:52 INFO - PID 17489 | |0;dojo.html;3841.3277676406624;3854.2484215577956;3862.57511673716;3902.726809123362;3934;6195.2255968004;6232.376762323771;6354.332795056522;6363.618182909023;6390.1373314602515;6255.717577896532;6268.185135932323;6283.560150789446;6307;6337.36626337366;5617.157426386034;5656.547476201904;5658.151277308396;5673.801416950408;5678.4605462480995;5440.6735595864175;5666.57500687448;5670.3479099903425;5694.857628559278;5748.080307150857;5609.691466969315;5614.6631202128;5683.630564013326;5719.370869204384;5772.365039845656;5697.857553561153;5707.200991861143;5776.075827867563;5777.8555536111935;5808.448197421239;5597.048501754712;5601.803936862231;5668.31980162379;5672.432756724335;5754.424557544254;6339.556231063827;6341.890169220368;6392.20097487814;6420;6433.388828061326;6865.691043903036;6880.071190389283;6881;6961.199462061877;6985.860282794367;6769.932300676979;6835.282295359015;6879.5184337096425;6937.410320122795;7039.1553013638695;6277.246730392337;6284.622922624611;6309.747610095591;6431.163948686641;6513.837154071186;6956.200036995709;7045.225025247219;7073.115860517435;7092.078029856086;7115.359617634438;6671.532992690562;6672.199336079701;6673.632950187689;6758.290379510179;6775.4918381121615;6779.93220067793;6814.965925170442;6863.965680171667;6906.171259448898;6920.9307906920285;6733.696983635748;6756.628385438749;6760.391564759097;6831;6848.931510684929;6745.8650826983185;6801.18385793708;6838.48711346651;6884;6896.310368963063;4942.728149951788;4955.40535135786;5004.899902002013;5007.4491805901325;5023.66872778714;4560.315952607069;4567.634589232854;4569.405977222939;4574.108048930427;4580.679352445297;6191.876162476725;6323.810285691437;6327.303996560375;6376.107344971708;6403.839904002437;6830.248672646005;6849.1780986281965;6850.794476165723;6888.758893438706;6892.448604111659;6307.779227727007;6324.462420694293;6325.9051114233325;6347.52393570484;6373.458256048288;5463.344398672105;5577.8047768327915;5584.078627026576;5588.273524441796;5596.692181930033;6891.483138764613;7012.9298707013295;7081.468889833283;7109.324614161646;7228.385587225145;6671.499637527108;6746.696398661974;6833.29308143867;6977;6978.755743548952;7015.543989640657;7055.6824942876665;7114.395276401357;7127.964360178374;7158.355747982707;6760.1549806274215;6823.897641535282;6835.794926152024;6858.759943402157;6860.2453730088655;6812.761553345609;6863.553868998499;6896.413804826646;6914.51598388108;6925.826854328682;6462;6526.314736952741;6577.3422657735755;6584.670766461753;6598.241202261801;6642;6644.634545100066;6654.567453115532;6665.700043497957;6684.4318232950745;6410.326915673973;6457.128287681103;6470.838229044311;6517.902231466626;6522.706478208587;6583.210014798255;6681;6718.126643536309;6761.121054262914;6762.120924279812;6380.298167201511;6442.8389290268115;6463.967680161569;6483.448906842971;6540 12:29:52 INFO - PID 17489 | |1;ext.html;962.9662961796339;983.0943591478524;987.6938149173752;990.7869807991281;994.1152374386795;44816.103677926425;45319.54680453194;45421;45516;45763.77118114413;42233.78883105588;42322.153556928846;42527.57472425275;42677.35983960235;42825.7858710706;7623.085229772435;7733.96133019337;7737.41969352298;7741.032370953631;7755.185705500926;7623.313901748841;7624.428167887403;7675;7696.923030769704;7710.228977102273;7683.5005724628;7923.445358824884;8631.395802293842;8640.740777776677;8665.30677545798;8442.15578442157;8525.360597955116;8530.61612227445;8531;8535.189157030072;8181.836363272701;8403.07566167721;8427.367947403907;8460.915390846074;8516.78708032298;41850.37224441636;41882.37176442325;42084;42166.578334216574;42354.364684529755;3594.0655429588232;3690.6862916652117;3698.260347930425;3705.370087085175;3705.4627079073384;34657.306853862785;34896.82551587226;34917.301653967035;34992.825035874914;35025.82487087574;5386.407495175527;5390.8382748517215;5449.4005659377435;5469.535089517396;5485.945140548583;216012.9199354009;228959;230101;230184;231390;48530;50272.24591631129;50503.98992020139;50613.74693126511;51039;52491.21263181055;52691;52746.47253527492;53302.200466993025;53369;16536.173191340386;16709.832901670827;16734.74897876533;16741.49775506737;16745.832541674426;14035.368408421646;14077.436902523988;14148.575542733528;14149.29253537319;14151.150930944177;14569.708605828037;14573;14673.266336683337;14695.485658001919;14817.333220005126;5659.434056594308;5677.120046392815;5732.197492351074;5848.4151584841175;5901.793437229677;1074.6990842564094;1086.2124959404368;1107.1640911112058;1117.7149826794116;1120.4061847220985;25276;25469.87265063663;25523.617145742828;25728.356791080372;25905.61141582878;1192.898603618702;1210.1891732539043;1214.1925619462959;1222.3888055972013;1232.3037483821613;13395.598132056053;13414.731705365837;13475;13580.592582222549;13710.862891370958;268474;268813;269019;269273;270921;58378.7081064592;58388.708056460295;58563;58622.36199371039;58680.70659646674;1506.9924650376897;1509.2830905319886;1532.785410042595;1541.5606552132588;1545.8840586956023;24728.25815225546;25067;25081;25184.49631007333;25346.366340841625;41243.7937810309;41429.58570414257;41440.79279603583;41442;41449.171016580105;237083;237293;238815;239186;240157;34687.306253875286;35006.47490287699;35113.648863511036;35690.46464303089;37590.624093759805;48515.27227091667;48610.513894860596;48772;48815;48921;35044.474332884514;35542;35619.82190089033;35723.82138089293;36195.63804362028;35012;35039.47440788441;35053.824730876186;35179;35393.646063540065;40794.79602601968;41370.79314603408;41403.378949315185;41428.58571414247;41482;33558;33909.66090339065;34651.826740866134;35231.823840881654;35389;25951.870240648677;26156.869215653798;26357.330782109482;26546.203613891616;26550.937962481283 12:29:52 INFO - PID 17489 | |2;jquery.html;606.2391698418486;614.2720875762222;619.4053708439898;620.913072169896;624.3382015064033;23279.650805237914;23489.530209395845;23703.170389036386;23775;24031.51936961262;23835.404114897137;25275;25356.619650705255;25428.364290892736;25435.61846572303;6551.508636852243;6741.3258674132685;6744.730210791562;6760.729570817161;6767.69545370457;6488.0916671666;6678.065070890079;6737;6740.898886516707;6816.591004539719;6701.061851340816;6716.126903502538;6717.529772915897;6721.462283017371;6724.260331363571;6365.936340636581;6650.600963942179;6671.199456065255;6684.9331506685285;6696.22993355763;6194.946859033975;6232.407921247474;6243.469305109071;6262.3111457739615;6263.31103578606;29867;30051.699483005108;30293.394132117453;30502.694973050206;30504.38991220163;2273.6475846243857;2321.0947730385165;2321.93034208974;2322.465832858452;2325.546518428907;20376;20786.688199677017;21261.149554017822;21368.145274189013;21477.355679329645;2541.301142185895;2560.219133164384;2601.7398260174023;2602.518534071184;2626.6716660417446;135966;140728;150512;154804;156915.99999999886;15369.923150384177;15940.521784346489;16069.758953615705;16161.353545858268;16959.236834342486;25646.358841029123;25857.870710646326;25902;25907.611385829226;26023.869880650476;10716.571337146426;11026.669199924016;11034.117270618332;11093.778124437466;11097.33415995043;9793.335133027402;9985.051420115078;10020.69524009482;10049.748756281006;10169.288149829588;9548.236141108695;9702.876277582;9710.417374957526;9963.900360996297;10147.543360548792;2807.7051909549614;2887.917265612495;2894.869730862074;2960.9703902960696;2970.3910698306795;1044.7858189071221;1048.638219814163;1073.061071562383;1075.4461452352045;1080.287010573018;20740.792592074187;20972.475688107614;21145.68281475779;21301;21306.78693213079;1113.7303474039602;1136.7499150186945;1140.7832511822728;1141.7145713571608;1153.4809335798925;11249.325040497597;11497.482613282422;11502.137339699557;11621.883781162249;11638.88361116378;508862;509344;522984;541982;547168;120868;122046.38976805059;124081;125930;128396;1136.2160109524402;1258.641287233134;1281.6731733407921;1287.9549215777404;1313.3302015971733;21270.680939785605;21309;21375.358739237847;21578.244761433274;21626.783732162476;61942.69028654828;63512;63708;64653.676731617925;64825;80414;81374.59312703398;81651;82184.58907705423;82733.58633206795;15102.320395582006;15481.225938702795;15601.921990389976;15699.529014129137;15722.842771572137;23208.767912321335;23792.286231413087;24198;24228;24408.877955610107;19752.30866919651;19866.10602522861;19991.500212494804;20133.49666258355;20268.18927242894;17191.398301059402;17714.114294284976;17799.19903604367;18105.818941810412;18150.18324175389;19989;20084.89957550203;20114;20327.4918127048;20459.693104603146;14869.702605947605;15188.316525756147;15198.240087995777;15268.3892644293;15271.312790924212;19310.903445482683;19348.32280870163;19390;19447.611047778682;19692.704609430584 12:29:52 INFO - PID 17489 | |3;mootools.html;1428.4786053090622;1465.6335916020994;1474.8598883106101;1493.0071502450871;1496.0126316631022;16376.344946202153;17049.232784524695;17064.48806535803;17076.402325918596;17121.828781712178;16612.75080873785;16707.83292167078;16745.911515751497;16782.160891955413;16784.664306713858;2868.1108856254627;2914.3734097169104;2926.7219614136625;2929.8828046878098;2937.588737576741;2763.585462180679;2885.2065681937424;2892.9710702893017;2898.30440694234;2908.461934542106;2803.6635603727477;2940.3678209185023;2940.794144409892;2943.8233705977605;2944.5288753799396;2885.307526193709;2886.956695649567;2911.970880291191;2913.796034277591;2915.387768568603;2700.1629494856656;2813.338865366637;2875.2811797050736;2966.3029188140767;2966.7626589872757;16700.916495417445;16920.915395423068;16944.322227111024;16965.660686786196;17010.659786804317;8441.451305665112;8702.390832641717;8748.256398206096;8756.211940925283;8777.166169213979;5556.805511807108;5794.130880367937;5876.676782776945;5891.116332550109;5924.496417804492;2100.2859027930576;2135.1566131378017;2145.356393082069;2152.2682288022147;2180.9563808723738;29709;29876.55185172224;29877.10368688943;29954.70045299541;30030.549541756893;7295.124585049828;7652.655630496641;7880.921190788019;8148;8194.467359621725;19648.21407143727;19743.901280493505;19765.505862353268;19877.403677889688;19893.801061989194;8500.27747641445;8631.395802293904;8678.349123815738;8680.435771674822;8683.2185103341;7901.486403383762;7924.4056695746895;7925.960370198112;7942.483738556975;8078.151794061657;5646.153077038477;5765.769369225184;5774.624649397776;5793.420657934173;5804.332501762267;1653.8594219491479;1734.1242672450333;1738.2612389734313;1743.9302427902996;1746.0833062641962;958.5399008475971;985;990.4552496127101;1006.8640733500883;1013.2704452793977;8377.37169712274;8415.368847336475;8458.534780587004;8542.615582298688;8632.827343453222;773.9483802294981;789.7986013566504;796.2634563029175;797.9521228726295;800.7437619961556;5729.656220626775;6050.273967123885;6254.655993920287;6436.935630643728;6913.2049814270995;24448.511029779303;24983.875080624843;25513;25877.74122258791;26021;23049.539009219723;24644.507109857703;24996.750032499807;25212.87393563057;25244.873775631004;12717;12743.171693839871;12883;13084.803727944089;13162.47350105985;9904.306698531034;9963.651272205729;10056.14522765544;10111.7472063199;10166.542505587118;21417.250396236057;21605.891970540677;21760.45598860041;22014.229501967355;22139;21931.342059738232;22236.555268894856;22314.88842555777;22604.773952260268;22873.771262287828;13681.657958551115;13854.238016909167;13956.371963261474;13959.790603141164;13967.4413023482;15418.92290538585;15733.527994160193;15787.684246315239;15823.287952041956;15848;12444.391126573193;12516.624501264976;12586.496540138292;12954.352282386031;13215.60353189406;11957.940210298893;12017.339046352185;12051.156419050581;12087.51649934028;12149.75700486003;10889.564417423215;11005.834912476479;11079.612213572485;11084.833727494255;11200.21598488098;8305.460145090792;8407.3694472916;8495.108013658477;8576.957115214633;8581.485110893367;8558.315334773328;8575.699850505202;8639.524826134622;8714.956425217833;8730.388872778845 12:29:52 INFO - PID 17489 | |4;prototype.html;553.3967974907353;558.2324304081889;578.1818726502;578.304633392621;592.0320276348169;6151.354107818676;6160.168377269068;6223.408776166257;6230.501559875211;6272.623642581442;6092.451679348857;6100.450959413652;6116.235470566179;6116.785912493053;6186.536009799261;4819.807207711688;4992.40091189056;4996.675216110941;4999.225120106371;5063.797448102081;4945.530174633422;4946.703197808125;4957.231629097496;4968.87577810554;4985.476524964881;4865.68373055752;4891.706497610137;4894.853154405356;4928.753562321869;5003.099442100421;4938.135826230413;4938.555530002282;4943.555080042795;4958.826441074581;4964.205727083649;4507.256302710049;4516.977415112903;4536.206163921333;4560.475545312298;4574.336721175411;6107.786227482061;6125.499252683121;6161.7227224775;6187.195664563623;6228.28374736902;5736.397678243808;5763.394843541451;5766.711664416763;5830.271216097988;5861.238039054895;6554;6593.967030164818;6610.289781189689;6618.56979296349;6635.966820165917;4872.123017856784;5051.924221136685;5059.114654935408;5068.163752980754;5095.235714642796;6667.4996876171435;6693.598384096969;6761.154855643044;6834.2140653824945;6912.585244885323;5124.359455068116;5139.820106296336;5160.174372100446;5181.792728290901;5202;5199.584033277329;5243.449437808975;5265.341832270966;5285.471452854684;5295.735213239323;5753.971230143906;5765.279340082489;5794.5364370851075;5811.79658711943;5844.707764611752;6595.835104122435;6624.436922861611;6624.668766561653;6664.933350666528;6693.866122677519;1810.646923849863;1817.7818661760673;1819.281383853397;1823.553229458791;1831.5146486181172;1236.3076677060874;1273.796192609178;1282.9550965716342;1284.26154960896;1285.64644722702;6017.488513476316;6104.694765261719;6120.296165940974;6158.199434073542;6181.598196117328;6397.168368112116;6445.355464453517;6518.5881223947445;6600.60396376219;6691.7323307068145;5960.433758792908;6064.636121832704;6067.605605635619;6095.786647467317;6118.877622447526;3443.1564266754303;3463.7055850252505;3494.4583589543663;3509.5262139611336;3515.191505953645;6880.8967865482055;6897.896531552031;6904.620245886525;6915.757948471779;6945.742633644086;6780.796576102724;6851.9657401712675;6852.760153394607;6915.757948471779;6933.583984960918;6779.796606101825;6871.828204294832;6876.830938740426;6984.126984127086;7030.137013691478;6311.274203466476;6450.967745161432;6483.805485835433;6611.438027767694;6636;6403.295637479967;6484.351564843667;6533.836654083686;6593.901091483727;6703.4972377070935;6910.963355496716;6943.51395402317;6953.721851125898;6991.650417479004;7005.474589405917;5122.231665250242;5142.408623008402;5148.279240906351;5156.767945442539;5193.558547523503;6292.790676754797;6413.615183089029;6440.388163124496;6557.573757705733;6667.233268174222;6177.84555386119;6277.811665650038;6322.178116844781;6364.268109167505;6406.4234218918655;6228.22147231596;6338.651374174465;6345.1434056401795;6383.808485745435;6411.198600174978;6232.906506402498;6284.4344009039405;6300.716467759054;6331.746730130743;6332.511859712871;6487.708053137714;6504.479641628753;6520.18497687789;6549.279579246184;6549.312322206099;6199.938000619936;6223.315435302204;6283.5287353449585;6295.874082518416;6321.241451025906 12:29:52 INFO - PID 17489 | |5;yui.html;931.9068093190682;968.2737946540094;976.4287891583423;992.9354591951522;993.8868467316606;23220.65169022466;23870.641940370908;25135.120270790525;25194.244172674804;25258.621120683205;22368.88815555924;23026.19408320709;23035.545721977225;23116.30651080466;23157.76842231577;1050.1336397472078;1065.2649671726522;1070.1813112968584;1072.1744256922166;1081.3944191252883;959.1559427703611;1060.6817954613584;1067.690369792763;1073.1736562846604;1083.2254937719529;1136.0286954653734;1139.880312567181;1140.9144314176428;1148.4602236948663;1149.3391300002531;1129.1192869561755;1134.982975255372;1136.7158210447387;1138.760860219355;1140.686311264409;827.7640872351351;833.8749187621845;837.1293854391425;838.2204549768712;843.4770442325797;17776.644467110586;19995.500112497157;20147;20515.692264616042;20658.793412065836;20344.084516196806;20628.793712062838;21068.473288167766;21086.261980830597;21154.259600913894;19016.904915475472;19201.135948882333;19225.13486893093;19253.22987080515;19256.711149332772;3881.7670939743425;3909.58949310324;3916.5495967963766;3920.627540383659;3983.4423180754716;160536;160948;161333;164144.17927910402;164487.3551264484;17936.19287132056;18151.273949042155;18250.634987300444;18314.725279120823;18417.81582184188;34410;34532.827335863665;34709.65290347115;34781.30437391238;34785;9264.67573634933;9296.8605470918;9330.113639204264;9330.440173589606;9411.294152938557;4435.800388982503;4485.551444855553;4501.032278060233;4528.343390208402;4536.296873984537;6151.200343955257;6236.6258024517765;6275.937240627535;6312.589681670676;6318.083877837687;1029.4183786160795;1069.5240617924949;1073.6671631794168;1081.1620993729923;1082.7726177502655;708.5854774956605;727.8798998165349;733.1349008170411;753.5666991479922;755.7279379423403;12547.372631368578;13095.476180952846;13214.537491187762;14070.929645351707;14316.928415357857;680.9080774095434;691.7233106757337;700.2472342232121;702.367868917978;710.9939435698532;6160.229971253593;6253.155823963707;6324.494040476751;6327.493800496041;6360.809175724736;137871;138892;142495.2875235617;142641;142963.2851835755;126924.36537817252;127323;129092;131977;132169.33915330362;112455;113079.4346028281;114647.42676286733;116337.8366216344;117845;11768.411579421167;11789.879961403652;11886.762264754829;11983.34091624969;12150.21023633461;52356.21465678094;53329.20006199832;53683.463165367844;54513.454865452426;54774;67201;69038.30961690319;69296.6535167321;70644.64677676784;70984.64507677428;15253;15554.766678500058;15607.297671604578;15693.843061569238;15785;18721.438356849318;18902.43292701221;18946.336878209197;19149.712754308417;19206.327778528244;16103.355865765237;16142.354305827635;16147.19264036817;16328.265228065004;16420.507384778477;16834.57913552122;16835.1582420876;16869.746953795457;17028.48914532566;17208;16012.919935400249;16095.75856362179;16179.43371981975;16269;16315.836841631432;12155.696107597381;12653.74692506163;12673.746525069262;12749.681257968625;12998.155119917554;13067.477300908238;13087.934560327518;13130;13142.211467311992;13241.404136814059 12:29:52 INFO - PID 17489 | __end_tp_report 12:29:52 INFO - PID 17489 | __start_cc_report 12:29:52 INFO - PID 17489 | _x_x_mozilla_cycle_collect,246 12:29:52 INFO - PID 17489 | __end_cc_report 12:29:52 INFO - PID 17489 | __startTimestamp1506367792088__endTimestamp 12:29:52 INFO - PID 17489 | 12:29:52 INFO - PID 17489 | ------- Summary: start ------- 12:29:52 INFO - PID 17489 | Number of tests: 6 12:29:52 INFO - PID 17489 | 12:29:52 INFO - PID 17489 | [#0] dojo.html Cycles:165 Average:6299.04 Median:6513.84 stddev:747.82 (11.5%) stddev-sans-first:724.83 12:29:52 INFO - PID 17489 | Values: 3841.3 3854.2 3862.6 3902.7 3934.0 6195.2 6232.4 6354.3 6363.6 6390.1 6255.7 6268.2 6283.6 6307.0 6337.4 5617.2 5656.5 5658.2 5673.8 5678.5 5440.7 5666.6 5670.3 5694.9 5748.1 5609.7 5614.7 5683.6 5719.4 5772.4 5697.9 5707.2 5776.1 5777.9 5808.4 5597.0 5601.8 5668.3 5672.4 5754.4 6339.6 6341.9 6392.2 6420.0 6433.4 6865.7 6880.1 6881.0 6961.2 6985.9 6769.9 6835.3 6879.5 6937.4 7039.2 6277.2 6284.6 6309.7 6431.2 6513.8 6956.2 7045.2 7073.1 7092.1 7115.4 6671.5 6672.2 6673.6 6758.3 6775.5 6779.9 6815.0 6864.0 6906.2 6920.9 6733.7 6756.6 6760.4 6831.0 6848.9 6745.9 6801.2 6838.5 6884.0 6896.3 4942.7 4955.4 5004.9 5007.4 5023.7 4560.3 4567.6 4569.4 4574.1 4580.7 6191.9 6323.8 6327.3 6376.1 6403.8 6830.2 6849.2 6850.8 6888.8 6892.4 6307.8 6324.5 6325.9 6347.5 6373.5 5463.3 5577.8 5584.1 5588.3 5596.7 6891.5 7012.9 7081.5 7109.3 7228.4 6671.5 6746.7 6833.3 6977.0 6978.8 7015.5 7055.7 7114.4 7128.0 7158.4 6760.2 6823.9 6835.8 6858.8 6860.2 6812.8 6863.6 6896.4 6914.5 6925.8 6462.0 6526.3 6577.3 6584.7 6598.2 6642.0 6644.6 6654.6 6665.7 6684.4 6410.3 6457.1 6470.8 6517.9 6522.7 6583.2 6681.0 6718.1 6761.1 6762.1 6380.3 6442.8 6464.0 6483.4 6540.0 12:29:52 INFO - PID 17489 | 12:29:52 INFO - PID 17489 | [#1] ext.html Cycles:180 Average:42506.85 Median:3694.47 stddev:63795.43 (1726.8%) stddev-sans-first:63898.13 12:29:52 INFO - PID 17489 | Values: 963.0 983.1 987.7 990.8 994.1 44816.1 45319.5 45421.0 45516.0 45763.8 42233.8 42322.2 42527.6 42677.4 42825.8 7623.1 7734.0 7737.4 7741.0 7755.2 7623.3 7624.4 7675.0 7696.9 7710.2 7683.5 7923.4 8631.4 8640.7 8665.3 8442.2 8525.4 8530.6 8531.0 8535.2 8181.8 8403.1 8427.4 8460.9 8516.8 41850.4 41882.4 42084.0 42166.6 42354.4 3594.1 3690.7 3698.3 3705.4 3705.5 34657.3 34896.8 34917.3 34992.8 35025.8 5386.4 5390.8 5449.4 5469.5 5485.9 216012.9 228959.0 230101.0 230184.0 231390.0 48530.0 50272.2 50504.0 50613.7 51039.0 52491.2 52691.0 52746.5 53302.2 53369.0 16536.2 16709.8 16734.7 16741.5 16745.8 14035.4 14077.4 14148.6 14149.3 14151.2 14569.7 14573.0 14673.3 14695.5 14817.3 5659.4 5677.1 5732.2 5848.4 5901.8 1074.7 1086.2 1107.2 1117.7 1120.4 25276.0 25469.9 25523.6 25728.4 25905.6 1192.9 1210.2 1214.2 1222.4 1232.3 13395.6 13414.7 13475.0 13580.6 13710.9 268474.0 268813.0 269019.0 269273.0 270921.0 58378.7 58388.7 58563.0 58622.4 58680.7 1507.0 1509.3 1532.8 1541.6 1545.9 24728.3 25067.0 25081.0 25184.5 25346.4 41243.8 41429.6 41440.8 41442.0 41449.2 237083.0 237293.0 238815.0 239186.0 240157.0 34687.3 35006.5 35113.6 35690.5 37590.6 48515.3 48610.5 48772.0 48815.0 48921.0 35044.5 35542.0 35619.8 35723.8 36195.6 35012.0 35039.5 35053.8 35179.0 35393.6 40794.8 41370.8 41403.4 41428.6 41482.0 33558.0 33909.7 34651.8 35231.8 35389.0 25951.9 26156.9 26357.3 26546.2 26550.9 12:29:52 INFO - PID 17489 | 12:29:52 INFO - PID 17489 | [#2] jquery.html Cycles:180 Average:37424.22 Median:12764.93 stddev:88798.90 (695.6%) stddev-sans-first:89004.98 12:29:52 INFO - PID 17489 | Values: 606.2 614.3 619.4 620.9 624.3 23279.7 23489.5 23703.2 23775.0 24031.5 23835.4 25275.0 25356.6 25428.4 25435.6 6551.5 6741.3 6744.7 6760.7 6767.7 6488.1 6678.1 6737.0 6740.9 6816.6 6701.1 6716.1 6717.5 6721.5 6724.3 6365.9 6650.6 6671.2 6684.9 6696.2 6194.9 6232.4 6243.5 6262.3 6263.3 29867.0 30051.7 30293.4 30502.7 30504.4 2273.6 2321.1 2321.9 2322.5 2325.5 20376.0 20786.7 21261.1 21368.1 21477.4 2541.3 2560.2 2601.7 2602.5 2626.7 135966.0 140728.0 150512.0 154804.0 156916.0 15369.9 15940.5 16069.8 16161.4 16959.2 25646.4 25857.9 25902.0 25907.6 26023.9 10716.6 11026.7 11034.1 11093.8 11097.3 9793.3 9985.1 10020.7 10049.7 10169.3 9548.2 9702.9 9710.4 9963.9 10147.5 2807.7 2887.9 2894.9 2961.0 2970.4 1044.8 1048.6 1073.1 1075.4 1080.3 20740.8 20972.5 21145.7 21301.0 21306.8 1113.7 1136.7 1140.8 1141.7 1153.5 11249.3 11497.5 11502.1 11621.9 11638.9 508862.0 509344.0 522984.0 541982.0 547168.0 120868.0 122046.4 124081.0 125930.0 128396.0 1136.2 1258.6 1281.7 1288.0 1313.3 21270.7 21309.0 21375.4 21578.2 21626.8 61942.7 63512.0 63708.0 64653.7 64825.0 80414.0 81374.6 81651.0 82184.6 82733.6 15102.3 15481.2 15601.9 15699.5 15722.8 23208.8 23792.3 24198.0 24228.0 24408.9 19752.3 19866.1 19991.5 20133.5 20268.2 17191.4 17714.1 17799.2 18105.8 18150.2 19989.0 20084.9 20114.0 20327.5 20459.7 14869.7 15188.3 15198.2 15268.4 15271.3 19310.9 19348.3 19390.0 19447.6 19692.7 12:29:52 INFO - PID 17489 | 12:29:52 INFO - PID 17489 | [#3] mootools.html Cycles:180 Average:10582.77 Median:2731.87 stddev:7702.25 (281.9%) stddev-sans-first:7693.15 12:29:52 INFO - PID 17489 | Values: 1428.5 1465.6 1474.9 1493.0 1496.0 16376.3 17049.2 17064.5 17076.4 17121.8 16612.8 16707.8 16745.9 16782.2 16784.7 2868.1 2914.4 2926.7 2929.9 2937.6 2763.6 2885.2 2893.0 2898.3 2908.5 2803.7 2940.4 2940.8 2943.8 2944.5 2885.3 2887.0 2912.0 2913.8 2915.4 2700.2 2813.3 2875.3 2966.3 2966.8 16700.9 16920.9 16944.3 16965.7 17010.7 8441.5 8702.4 8748.3 8756.2 8777.2 5556.8 5794.1 5876.7 5891.1 5924.5 2100.3 2135.2 2145.4 2152.3 2181.0 29709.0 29876.6 29877.1 29954.7 30030.5 7295.1 7652.7 7880.9 8148.0 8194.5 19648.2 19743.9 19765.5 19877.4 19893.8 8500.3 8631.4 8678.3 8680.4 8683.2 7901.5 7924.4 7926.0 7942.5 8078.2 5646.2 5765.8 5774.6 5793.4 5804.3 1653.9 1734.1 1738.3 1743.9 1746.1 958.5 985.0 990.5 1006.9 1013.3 8377.4 8415.4 8458.5 8542.6 8632.8 773.9 789.8 796.3 798.0 800.7 5729.7 6050.3 6254.7 6436.9 6913.2 24448.5 24983.9 25513.0 25877.7 26021.0 23049.5 24644.5 24996.8 25212.9 25244.9 12717.0 12743.2 12883.0 13084.8 13162.5 9904.3 9963.7 10056.1 10111.7 10166.5 21417.3 21605.9 21760.5 22014.2 22139.0 21931.3 22236.6 22314.9 22604.8 22873.8 13681.7 13854.2 13956.4 13959.8 13967.4 15418.9 15733.5 15787.7 15823.3 15848.0 12444.4 12516.6 12586.5 12954.4 13215.6 11957.9 12017.3 12051.2 12087.5 12149.8 10889.6 11005.8 11079.6 11084.8 11200.2 8305.5 8407.4 8495.1 8577.0 8581.5 8558.3 8575.7 8639.5 8715.0 8730.4 12:29:52 INFO - PID 17489 | 12:29:52 INFO - PID 17489 | [#4] prototype.html Cycles:180 Average:5549.78 Median:6159.18 stddev:1536.05 (24.9%) stddev-sans-first:1493.88 12:29:52 INFO - PID 17489 | Values: 553.4 558.2 578.2 578.3 592.0 6151.4 6160.2 6223.4 6230.5 6272.6 6092.5 6100.5 6116.2 6116.8 6186.5 4819.8 4992.4 4996.7 4999.2 5063.8 4945.5 4946.7 4957.2 4968.9 4985.5 4865.7 4891.7 4894.9 4928.8 5003.1 4938.1 4938.6 4943.6 4958.8 4964.2 4507.3 4517.0 4536.2 4560.5 4574.3 6107.8 6125.5 6161.7 6187.2 6228.3 5736.4 5763.4 5766.7 5830.3 5861.2 6554.0 6594.0 6610.3 6618.6 6636.0 4872.1 5051.9 5059.1 5068.2 5095.2 6667.5 6693.6 6761.2 6834.2 6912.6 5124.4 5139.8 5160.2 5181.8 5202.0 5199.6 5243.4 5265.3 5285.5 5295.7 5754.0 5765.3 5794.5 5811.8 5844.7 6595.8 6624.4 6624.7 6664.9 6693.9 1810.6 1817.8 1819.3 1823.6 1831.5 1236.3 1273.8 1283.0 1284.3 1285.6 6017.5 6104.7 6120.3 6158.2 6181.6 6397.2 6445.4 6518.6 6600.6 6691.7 5960.4 6064.6 6067.6 6095.8 6118.9 3443.2 3463.7 3494.5 3509.5 3515.2 6880.9 6897.9 6904.6 6915.8 6945.7 6780.8 6852.0 6852.8 6915.8 6933.6 6779.8 6871.8 6876.8 6984.1 7030.1 6311.3 6451.0 6483.8 6611.4 6636.0 6403.3 6484.4 6533.8 6593.9 6703.5 6911.0 6943.5 6953.7 6991.7 7005.5 5122.2 5142.4 5148.3 5156.8 5193.6 6292.8 6413.6 6440.4 6557.6 6667.2 6177.8 6277.8 6322.2 6364.3 6406.4 6228.2 6338.7 6345.1 6383.8 6411.2 6232.9 6284.4 6300.7 6331.7 6332.5 6487.7 6504.5 6520.2 6549.3 6549.3 6199.9 6223.3 6283.5 6295.9 6321.2 12:29:52 INFO - PID 17489 | 12:29:52 INFO - PID 17489 | [#5] yui.html Cycles:180 Average:27918.31 Median:18811.94 stddev:41609.95 (221.2%) stddev-sans-first:41677.33 12:29:52 INFO - PID 17489 | Values: 931.9 968.3 976.4 992.9 993.9 23220.7 23870.6 25135.1 25194.2 25258.6 22368.9 23026.2 23035.5 23116.3 23157.8 1050.1 1065.3 1070.2 1072.2 1081.4 959.2 1060.7 1067.7 1073.2 1083.2 1136.0 1139.9 1140.9 1148.5 1149.3 1129.1 1135.0 1136.7 1138.8 1140.7 827.8 833.9 837.1 838.2 843.5 17776.6 19995.5 20147.0 20515.7 20658.8 20344.1 20628.8 21068.5 21086.3 21154.3 19016.9 19201.1 19225.1 19253.2 19256.7 3881.8 3909.6 3916.5 3920.6 3983.4 160536.0 160948.0 161333.0 164144.2 164487.4 17936.2 18151.3 18250.6 18314.7 18417.8 34410.0 34532.8 34709.7 34781.3 34785.0 9264.7 9296.9 9330.1 9330.4 9411.3 4435.8 4485.6 4501.0 4528.3 4536.3 6151.2 6236.6 6275.9 6312.6 6318.1 1029.4 1069.5 1073.7 1081.2 1082.8 708.6 727.9 733.1 753.6 755.7 12547.4 13095.5 13214.5 14070.9 14316.9 680.9 691.7 700.2 702.4 711.0 6160.2 6253.2 6324.5 6327.5 6360.8 137871.0 138892.0 142495.3 142641.0 142963.3 126924.4 127323.0 129092.0 131977.0 132169.3 112455.0 113079.4 114647.4 116337.8 117845.0 11768.4 11789.9 11886.8 11983.3 12150.2 52356.2 53329.2 53683.5 54513.5 54774.0 67201.0 69038.3 69296.7 70644.6 70984.6 15253.0 15554.8 15607.3 15693.8 15785.0 18721.4 18902.4 18946.3 19149.7 19206.3 16103.4 16142.4 16147.2 16328.3 16420.5 16834.6 16835.2 16869.7 17028.5 17208.0 16012.9 16095.8 16179.4 16269.0 16315.8 12155.7 12653.7 12673.7 12749.7 12998.2 13067.5 13087.9 13130.0 13142.2 13241.4 12:29:52 INFO - PID 17489 | -------- Summary: end -------- 12:29:52 INFO - PID 17489 | 12:29:52 INFO - TEST-INFO | 17489: exit 0 12:29:52 INFO - TEST-OK | dromaeo_css | took 1109101ms 12:29:52 INFO - TEST-START | kraken 12:29:52 INFO - Initialising browser for kraken test... 12:29:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55324/getInfo.html -profile /tmp/tmpaFOeHZ/profile 12:29:52 INFO - TEST-INFO | started process 17867 (/builds/slave/test/build/application/firefox/firefox http://localhost:55324/getInfo.html) 12:29:57 INFO - TEST-INFO | 17867: exit 0 12:29:57 INFO - Browser initialized. 12:29:57 INFO - Running cycle 1/1 for kraken test... 12:29:57 INFO - Using env: {'DISPLAY': ':0', 12:29:57 INFO - 'HOME': '/home/cltbld', 12:29:57 INFO - 'JSGC_DISABLE_POISONING': '1', 12:29:57 INFO - 'LANG': 'en_US.UTF-8', 12:29:57 INFO - 'LANGUAGE': 'en_US:en', 12:29:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:29:57 INFO - 'LOGNAME': 'cltbld', 12:29:57 INFO - 'MAIL': '/var/mail/cltbld', 12:29:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:29:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:29:57 INFO - 'MOZ_CRASHREPORTER': '1', 12:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:29:57 INFO - 'MOZ_NO_REMOTE': '1', 12:29:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:29:57 INFO - 'NO_EM_RESTART': '1', 12:29:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:57 INFO - 'PWD': '/builds/slave/test', 12:29:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:29:57 INFO - 'RUST_BACKTRACE': 'full', 12:29:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:29:57 INFO - 'SHELL': '/bin/bash', 12:29:57 INFO - 'SHLVL': '1', 12:29:57 INFO - 'TERM': 'linux', 12:29:57 INFO - 'TMOUT': '86400', 12:29:57 INFO - 'USER': 'cltbld', 12:29:57 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053', 12:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:57 INFO - '_': '/tools/buildbot/bin/python'} 12:29:57 INFO - TEST-INFO | started process 18102 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaFOeHZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:29:58 INFO - PID 18102 | 12:29:58 INFO - PID 18102 | (/builds/slave/test/build/application/firefox/firefox:18151): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:29:58 INFO - PID 18102 | 12:30:09 INFO - PID 18102 | RSS: Main: 145022976 12:30:09 INFO - PID 18102 | 12:30:11 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55324/tests/kraken/driver.html?testName=audio-beat-detection) 12:30:12 INFO - PID 18102 | RSS: Main: 151052288 12:30:12 INFO - PID 18102 | 12:30:15 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55324/tests/kraken/driver.html?testName=audio-dft) 12:30:15 INFO - PID 18102 | RSS: Main: 151269376 12:30:15 INFO - PID 18102 | 12:30:18 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55324/tests/kraken/driver.html?testName=audio-fft) 12:30:19 INFO - PID 18102 | RSS: Main: 148340736 12:30:19 INFO - PID 18102 | 12:30:21 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55324/tests/kraken/driver.html?testName=audio-oscillator) 12:30:21 INFO - PID 18102 | RSS: Main: 148959232 12:30:21 INFO - PID 18102 | 12:30:23 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55324/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:30:24 INFO - PID 18102 | RSS: Main: 148860928 12:30:24 INFO - PID 18102 | 12:30:27 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55324/tests/kraken/driver.html?testName=imaging-darkroom) 12:30:28 INFO - PID 18102 | RSS: Main: 148660224 12:30:28 INFO - PID 18102 | 12:30:31 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55324/tests/kraken/driver.html?testName=imaging-desaturate) 12:30:32 INFO - PID 18102 | RSS: Main: 150028288 12:30:32 INFO - PID 18102 | 12:30:35 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55324/tests/kraken/driver.html?testName=json-parse-financial) 12:30:35 INFO - PID 18102 | RSS: Main: 151896064 12:30:35 INFO - PID 18102 | 12:30:37 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55324/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:30:38 INFO - PID 18102 | RSS: Main: 151957504 12:30:38 INFO - PID 18102 | 12:30:39 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55324/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:30:40 INFO - PID 18102 | RSS: Main: 151171072 12:30:40 INFO - PID 18102 | 12:30:42 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55324/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:30:42 INFO - PID 18102 | RSS: Main: 148127744 12:30:42 INFO - PID 18102 | 12:30:45 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:30:46 INFO - PID 18102 | RSS: Main: 148791296 12:30:46 INFO - PID 18102 | 12:30:48 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:30:49 INFO - PID 18102 | RSS: Main: 149032960 12:30:49 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | Cycle 1(1): loaded http://localhost:55324/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55324/tests/kraken/driver.html?testName=ai-astar) 12:30:51 INFO - PID 18102 | RSS: Main: 150597632 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | __start_tp_report 12:30:51 INFO - PID 18102 | _x_x_mozilla_page_load 12:30:51 INFO - PID 18102 | _x_x_mozilla_page_load_details 12:30:51 INFO - PID 18102 | |i|pagename|runs| 12:30:51 INFO - PID 18102 | |0;ai-astar;107;106;106;107;106;109;108;105;107;106 12:30:51 INFO - PID 18102 | |1;audio-beat-detection;115;117;112;113;113;115;113;111;142;114 12:30:51 INFO - PID 18102 | |2;audio-dft;163;164;165;166;164;164;164;163;171;172 12:30:51 INFO - PID 18102 | |3;audio-fft;73;75;74;74;74;77;73;74;75;74 12:30:51 INFO - PID 18102 | |4;audio-oscillator;79;80;79;78;78;77;77;77;77;77 12:30:51 INFO - PID 18102 | |5;imaging-gaussian-blur;117;118;116;114;113;115;116;114;115;114 12:30:51 INFO - PID 18102 | |6;imaging-darkroom;150;153;152;150;151;154;151;150;153;152 12:30:51 INFO - PID 18102 | |7;imaging-desaturate;99;94;95;94;96;94;94;94;94;95 12:30:51 INFO - PID 18102 | |8;json-parse-financial;45;44;54;47;45;47;49;46;44;44 12:30:51 INFO - PID 18102 | |9;json-stringify-tinderbox;38;37;39;38;38;37;39;37;41;38 12:30:51 INFO - PID 18102 | |10;stanford-crypto-aes;70;71;71;74;73;72;73;71;71;80 12:30:51 INFO - PID 18102 | |11;stanford-crypto-ccm;112;110;107;108;110;108;111;105;108;107 12:30:51 INFO - PID 18102 | |12;stanford-crypto-pbkdf2;161;153;159;152;152;154;157;161;153;155 12:30:51 INFO - PID 18102 | |13;stanford-crypto-sha256-iterative;60;62;60;62;61;60;58;59;61;62 12:30:51 INFO - PID 18102 | __end_tp_report 12:30:51 INFO - PID 18102 | __start_cc_report 12:30:51 INFO - PID 18102 | _x_x_mozilla_cycle_collect,722 12:30:51 INFO - PID 18102 | __end_cc_report 12:30:51 INFO - PID 18102 | __startTimestamp1506367851323__endTimestamp 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | ------- Summary: start ------- 12:30:51 INFO - PID 18102 | Number of tests: 14 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#0] ai-astar Cycles:10 Average:106.70 Median:107.00 stddev:1.16 (1.1%) stddev-sans-first:1.22 12:30:51 INFO - PID 18102 | Values: 107.0 106.0 106.0 107.0 106.0 109.0 108.0 105.0 107.0 106.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#1] audio-beat-detection Cycles:10 Average:116.50 Median:114.50 stddev:9.12 (8.0%) stddev-sans-first:9.66 12:30:51 INFO - PID 18102 | Values: 115.0 117.0 112.0 113.0 113.0 115.0 113.0 111.0 142.0 114.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#2] audio-dft Cycles:10 Average:165.60 Median:164.50 stddev:3.24 (2.0%) stddev-sans-first:3.30 12:30:51 INFO - PID 18102 | Values: 163.0 164.0 165.0 166.0 164.0 164.0 164.0 163.0 171.0 172.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#3] audio-fft Cycles:10 Average:74.30 Median:74.00 stddev:1.16 (1.6%) stddev-sans-first:1.13 12:30:51 INFO - PID 18102 | Values: 73.0 75.0 74.0 74.0 74.0 77.0 73.0 74.0 75.0 74.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:78.00 stddev:1.10 (1.4%) stddev-sans-first:1.09 12:30:51 INFO - PID 18102 | Values: 79.0 80.0 79.0 78.0 78.0 77.0 77.0 77.0 77.0 77.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:115.50 stddev:1.55 (1.3%) stddev-sans-first:1.50 12:30:51 INFO - PID 18102 | Values: 117.0 118.0 116.0 114.0 113.0 115.0 116.0 114.0 115.0 114.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#6] imaging-darkroom Cycles:10 Average:151.60 Median:152.00 stddev:1.43 (0.9%) stddev-sans-first:1.39 12:30:51 INFO - PID 18102 | Values: 150.0 153.0 152.0 150.0 151.0 154.0 151.0 150.0 153.0 152.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.50 stddev:1.60 (1.7%) stddev-sans-first:0.73 12:30:51 INFO - PID 18102 | Values: 99.0 94.0 95.0 94.0 96.0 94.0 94.0 94.0 94.0 95.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#8] json-parse-financial Cycles:10 Average:46.50 Median:46.50 stddev:3.10 (6.7%) stddev-sans-first:3.24 12:30:51 INFO - PID 18102 | Values: 45.0 44.0 54.0 47.0 45.0 47.0 49.0 46.0 44.0 44.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:1.23 (3.2%) stddev-sans-first:1.30 12:30:51 INFO - PID 18102 | Values: 38.0 37.0 39.0 38.0 38.0 37.0 39.0 37.0 41.0 38.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:72.50 stddev:2.88 (4.0%) stddev-sans-first:2.89 12:30:51 INFO - PID 18102 | Values: 70.0 71.0 71.0 74.0 73.0 72.0 73.0 71.0 71.0 80.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#11] stanford-crypto-ccm Cycles:10 Average:108.60 Median:109.00 stddev:2.12 (1.9%) stddev-sans-first:1.86 12:30:51 INFO - PID 18102 | Values: 112.0 110.0 107.0 108.0 110.0 108.0 111.0 105.0 108.0 107.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.70 Median:156.00 stddev:3.56 (2.3%) stddev-sans-first:3.22 12:30:51 INFO - PID 18102 | Values: 161.0 153.0 159.0 152.0 152.0 154.0 157.0 161.0 153.0 155.0 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.50 Median:61.00 stddev:1.35 (2.2%) stddev-sans-first:1.42 12:30:51 INFO - PID 18102 | Values: 60.0 62.0 60.0 62.0 61.0 60.0 58.0 59.0 61.0 62.0 12:30:51 INFO - PID 18102 | -------- Summary: end -------- 12:30:51 INFO - PID 18102 | 12:30:51 INFO - PID 18102 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:30:51 INFO - TEST-INFO | 18102: exit 0 12:30:51 INFO - TEST-OK | kraken | took 59156ms 12:30:51 INFO - SUITE-END | took 1168s 12:30:51 INFO - Completed test suite (00:19:28) 12:30:51 INFO - javascript benchmark 12:30:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9066.016651061664, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3841.3277676406624, 3854.2484215577956, 3862.57511673716, 3902.726809123362, 3934.0, 6195.2255968004, 6232.376762323771, 6354.332795056522, 6363.618182909023, 6390.1373314602515, 6255.717577896532, 6268.185135932323, 6283.560150789446, 6307.0, 6337.36626337366, 5617.157426386034, 5656.547476201904, 5658.151277308396, 5673.801416950408, 5678.4605462480995, 5440.6735595864175, 5666.57500687448, 5670.3479099903425, 5694.857628559278, 5748.080307150857, 5609.691466969315, 5614.6631202128, 5683.630564013326, 5719.370869204384, 5772.365039845656, 5697.857553561153, 5707.200991861143, 5776.075827867563, 5777.8555536111935, 5808.448197421239, 5597.048501754712, 5601.803936862231, 5668.31980162379, 5672.432756724335, 5754.424557544254, 6339.556231063827, 6341.890169220368, 6392.20097487814, 6420.0, 6433.388828061326, 6865.691043903036, 6880.071190389283, 6881.0, 6961.199462061877, 6985.860282794367, 6769.932300676979, 6835.282295359015, 6879.5184337096425, 6937.410320122795, 7039.1553013638695, 6277.246730392337, 6284.622922624611, 6309.747610095591, 6431.163948686641, 6513.837154071186, 6956.200036995709, 7045.225025247219, 7073.115860517435, 7092.078029856086, 7115.359617634438, 6671.532992690562, 6672.199336079701, 6673.632950187689, 6758.290379510179, 6775.4918381121615, 6779.93220067793, 6814.965925170442, 6863.965680171667, 6906.171259448898, 6920.9307906920285, 6733.696983635748, 6756.628385438749, 6760.391564759097, 6831.0, 6848.931510684929, 6745.8650826983185, 6801.18385793708, 6838.48711346651, 6884.0, 6896.310368963063, 4942.728149951788, 4955.40535135786, 5004.899902002013, 5007.4491805901325, 5023.66872778714, 4560.315952607069, 4567.634589232854, 4569.405977222939, 4574.108048930427, 4580.679352445297, 6191.876162476725, 6323.810285691437, 6327.303996560375, 6376.107344971708, 6403.839904002437, 6830.248672646005, 6849.1780986281965, 6850.794476165723, 6888.758893438706, 6892.448604111659, 6307.779227727007, 6324.462420694293, 6325.9051114233325, 6347.52393570484, 6373.458256048288, 5463.344398672105, 5577.8047768327915, 5584.078627026576, 5588.273524441796, 5596.692181930033, 6891.483138764613, 7012.9298707013295, 7081.468889833283, 7109.324614161646, 7228.385587225145, 6671.499637527108, 6746.696398661974, 6833.29308143867, 6977.0, 6978.755743548952, 7015.543989640657, 7055.6824942876665, 7114.395276401357, 7127.964360178374, 7158.355747982707, 6760.1549806274215, 6823.897641535282, 6835.794926152024, 6858.759943402157, 6860.2453730088655, 6812.761553345609, 6863.553868998499, 6896.413804826646, 6914.51598388108, 6925.826854328682, 6462.0, 6526.314736952741, 6577.3422657735755, 6584.670766461753, 6598.241202261801, 6642.0, 6644.634545100066, 6654.567453115532, 6665.700043497957, 6684.4318232950745, 6410.326915673973, 6457.128287681103, 6470.838229044311, 6517.902231466626, 6522.706478208587, 6583.210014798255, 6681.0, 6718.126643536309, 6761.121054262914, 6762.120924279812, 6380.298167201511, 6442.8389290268115, 6463.967680161569, 6483.448906842971, 6540.0], "value": 6248.257803983102, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [962.9662961796339, 983.0943591478524, 987.6938149173752, 990.7869807991281, 994.1152374386795, 44816.103677926425, 45319.54680453194, 45421.0, 45516.0, 45763.77118114413, 42233.78883105588, 42322.153556928846, 42527.57472425275, 42677.35983960235, 42825.7858710706, 7623.085229772435, 7733.96133019337, 7737.41969352298, 7741.032370953631, 7755.185705500926, 7623.313901748841, 7624.428167887403, 7675.0, 7696.923030769704, 7710.228977102273, 7683.5005724628, 7923.445358824884, 8631.395802293842, 8640.740777776677, 8665.30677545798, 8442.15578442157, 8525.360597955116, 8530.61612227445, 8531.0, 8535.189157030072, 8181.836363272701, 8403.07566167721, 8427.367947403907, 8460.915390846074, 8516.78708032298, 41850.37224441636, 41882.37176442325, 42084.0, 42166.578334216574, 42354.364684529755, 3594.0655429588232, 3690.6862916652117, 3698.260347930425, 3705.370087085175, 3705.4627079073384, 34657.306853862785, 34896.82551587226, 34917.301653967035, 34992.825035874914, 35025.82487087574, 5386.407495175527, 5390.8382748517215, 5449.4005659377435, 5469.535089517396, 5485.945140548583, 216012.9199354009, 228959.0, 230101.0, 230184.0, 231390.0, 48530.0, 50272.24591631129, 50503.98992020139, 50613.74693126511, 51039.0, 52491.21263181055, 52691.0, 52746.47253527492, 53302.200466993025, 53369.0, 16536.173191340386, 16709.832901670827, 16734.74897876533, 16741.49775506737, 16745.832541674426, 14035.368408421646, 14077.436902523988, 14148.575542733528, 14149.29253537319, 14151.150930944177, 14569.708605828037, 14573.0, 14673.266336683337, 14695.485658001919, 14817.333220005126, 5659.434056594308, 5677.120046392815, 5732.197492351074, 5848.4151584841175, 5901.793437229677, 1074.6990842564094, 1086.2124959404368, 1107.1640911112058, 1117.7149826794116, 1120.4061847220985, 25276.0, 25469.87265063663, 25523.617145742828, 25728.356791080372, 25905.61141582878, 1192.898603618702, 1210.1891732539043, 1214.1925619462959, 1222.3888055972013, 1232.3037483821613, 13395.598132056053, 13414.731705365837, 13475.0, 13580.592582222549, 13710.862891370958, 268474.0, 268813.0, 269019.0, 269273.0, 270921.0, 58378.7081064592, 58388.708056460295, 58563.0, 58622.36199371039, 58680.70659646674, 1506.9924650376897, 1509.2830905319886, 1532.785410042595, 1541.5606552132588, 1545.8840586956023, 24728.25815225546, 25067.0, 25081.0, 25184.49631007333, 25346.366340841625, 41243.7937810309, 41429.58570414257, 41440.79279603583, 41442.0, 41449.171016580105, 237083.0, 237293.0, 238815.0, 239186.0, 240157.0, 34687.306253875286, 35006.47490287699, 35113.648863511036, 35690.46464303089, 37590.624093759805, 48515.27227091667, 48610.513894860596, 48772.0, 48815.0, 48921.0, 35044.474332884514, 35542.0, 35619.82190089033, 35723.82138089293, 36195.63804362028, 35012.0, 35039.47440788441, 35053.824730876186, 35179.0, 35393.646063540065, 40794.79602601968, 41370.79314603408, 41403.378949315185, 41428.58571414247, 41482.0, 33558.0, 33909.66090339065, 34651.826740866134, 35231.823840881654, 35389.0, 25951.870240648677, 26156.869215653798, 26357.330782109482, 26546.203613891616, 26550.937962481283], "value": 18627.05041898345, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.2391698418486, 614.2720875762222, 619.4053708439898, 620.913072169896, 624.3382015064033, 23279.650805237914, 23489.530209395845, 23703.170389036386, 23775.0, 24031.51936961262, 23835.404114897137, 25275.0, 25356.619650705255, 25428.364290892736, 25435.61846572303, 6551.508636852243, 6741.3258674132685, 6744.730210791562, 6760.729570817161, 6767.69545370457, 6488.0916671666, 6678.065070890079, 6737.0, 6740.898886516707, 6816.591004539719, 6701.061851340816, 6716.126903502538, 6717.529772915897, 6721.462283017371, 6724.260331363571, 6365.936340636581, 6650.600963942179, 6671.199456065255, 6684.9331506685285, 6696.22993355763, 6194.946859033975, 6232.407921247474, 6243.469305109071, 6262.3111457739615, 6263.31103578606, 29867.0, 30051.699483005108, 30293.394132117453, 30502.694973050206, 30504.38991220163, 2273.6475846243857, 2321.0947730385165, 2321.93034208974, 2322.465832858452, 2325.546518428907, 20376.0, 20786.688199677017, 21261.149554017822, 21368.145274189013, 21477.355679329645, 2541.301142185895, 2560.219133164384, 2601.7398260174023, 2602.518534071184, 2626.6716660417446, 135966.0, 140728.0, 150512.0, 154804.0, 156915.99999999886, 15369.923150384177, 15940.521784346489, 16069.758953615705, 16161.353545858268, 16959.236834342486, 25646.358841029123, 25857.870710646326, 25902.0, 25907.611385829226, 26023.869880650476, 10716.571337146426, 11026.669199924016, 11034.117270618332, 11093.778124437466, 11097.33415995043, 9793.335133027402, 9985.051420115078, 10020.69524009482, 10049.748756281006, 10169.288149829588, 9548.236141108695, 9702.876277582, 9710.417374957526, 9963.900360996297, 10147.543360548792, 2807.7051909549614, 2887.917265612495, 2894.869730862074, 2960.9703902960696, 2970.3910698306795, 1044.7858189071221, 1048.638219814163, 1073.061071562383, 1075.4461452352045, 1080.287010573018, 20740.792592074187, 20972.475688107614, 21145.68281475779, 21301.0, 21306.78693213079, 1113.7303474039602, 1136.7499150186945, 1140.7832511822728, 1141.7145713571608, 1153.4809335798925, 11249.325040497597, 11497.482613282422, 11502.137339699557, 11621.883781162249, 11638.88361116378, 508862.0, 509344.0, 522984.0, 541982.0, 547168.0, 120868.0, 122046.38976805059, 124081.0, 125930.0, 128396.0, 1136.2160109524402, 1258.641287233134, 1281.6731733407921, 1287.9549215777404, 1313.3302015971733, 21270.680939785605, 21309.0, 21375.358739237847, 21578.244761433274, 21626.783732162476, 61942.69028654828, 63512.0, 63708.0, 64653.676731617925, 64825.0, 80414.0, 81374.59312703398, 81651.0, 82184.58907705423, 82733.58633206795, 15102.320395582006, 15481.225938702795, 15601.921990389976, 15699.529014129137, 15722.842771572137, 23208.767912321335, 23792.286231413087, 24198.0, 24228.0, 24408.877955610107, 19752.30866919651, 19866.10602522861, 19991.500212494804, 20133.49666258355, 20268.18927242894, 17191.398301059402, 17714.114294284976, 17799.19903604367, 18105.818941810412, 18150.18324175389, 19989.0, 20084.89957550203, 20114.0, 20327.4918127048, 20459.693104603146, 14869.702605947605, 15188.316525756147, 15198.240087995777, 15268.3892644293, 15271.312790924212, 19310.903445482683, 19348.32280870163, 19390.0, 19447.611047778682, 19692.704609430584], "value": 12794.471930533893, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.4786053090622, 1465.6335916020994, 1474.8598883106101, 1493.0071502450871, 1496.0126316631022, 16376.344946202153, 17049.232784524695, 17064.48806535803, 17076.402325918596, 17121.828781712178, 16612.75080873785, 16707.83292167078, 16745.911515751497, 16782.160891955413, 16784.664306713858, 2868.1108856254627, 2914.3734097169104, 2926.7219614136625, 2929.8828046878098, 2937.588737576741, 2763.585462180679, 2885.2065681937424, 2892.9710702893017, 2898.30440694234, 2908.461934542106, 2803.6635603727477, 2940.3678209185023, 2940.794144409892, 2943.8233705977605, 2944.5288753799396, 2885.307526193709, 2886.956695649567, 2911.970880291191, 2913.796034277591, 2915.387768568603, 2700.1629494856656, 2813.338865366637, 2875.2811797050736, 2966.3029188140767, 2966.7626589872757, 16700.916495417445, 16920.915395423068, 16944.322227111024, 16965.660686786196, 17010.659786804317, 8441.451305665112, 8702.390832641717, 8748.256398206096, 8756.211940925283, 8777.166169213979, 5556.805511807108, 5794.130880367937, 5876.676782776945, 5891.116332550109, 5924.496417804492, 2100.2859027930576, 2135.1566131378017, 2145.356393082069, 2152.2682288022147, 2180.9563808723738, 29709.0, 29876.55185172224, 29877.10368688943, 29954.70045299541, 30030.549541756893, 7295.124585049828, 7652.655630496641, 7880.921190788019, 8148.0, 8194.467359621725, 19648.21407143727, 19743.901280493505, 19765.505862353268, 19877.403677889688, 19893.801061989194, 8500.27747641445, 8631.395802293904, 8678.349123815738, 8680.435771674822, 8683.2185103341, 7901.486403383762, 7924.4056695746895, 7925.960370198112, 7942.483738556975, 8078.151794061657, 5646.153077038477, 5765.769369225184, 5774.624649397776, 5793.420657934173, 5804.332501762267, 1653.8594219491479, 1734.1242672450333, 1738.2612389734313, 1743.9302427902996, 1746.0833062641962, 958.5399008475971, 985.0, 990.4552496127101, 1006.8640733500883, 1013.2704452793977, 8377.37169712274, 8415.368847336475, 8458.534780587004, 8542.615582298688, 8632.827343453222, 773.9483802294981, 789.7986013566504, 796.2634563029175, 797.9521228726295, 800.7437619961556, 5729.656220626775, 6050.273967123885, 6254.655993920287, 6436.935630643728, 6913.2049814270995, 24448.511029779303, 24983.875080624843, 25513.0, 25877.74122258791, 26021.0, 23049.539009219723, 24644.507109857703, 24996.750032499807, 25212.87393563057, 25244.873775631004, 12717.0, 12743.171693839871, 12883.0, 13084.803727944089, 13162.47350105985, 9904.306698531034, 9963.651272205729, 10056.14522765544, 10111.7472063199, 10166.542505587118, 21417.250396236057, 21605.891970540677, 21760.45598860041, 22014.229501967355, 22139.0, 21931.342059738232, 22236.555268894856, 22314.88842555777, 22604.773952260268, 22873.771262287828, 13681.657958551115, 13854.238016909167, 13956.371963261474, 13959.790603141164, 13967.4413023482, 15418.92290538585, 15733.527994160193, 15787.684246315239, 15823.287952041956, 15848.0, 12444.391126573193, 12516.624501264976, 12586.496540138292, 12954.352282386031, 13215.60353189406, 11957.940210298893, 12017.339046352185, 12051.156419050581, 12087.51649934028, 12149.75700486003, 10889.564417423215, 11005.834912476479, 11079.612213572485, 11084.833727494255, 11200.21598488098, 8305.460145090792, 8407.3694472916, 8495.108013658477, 8576.957115214633, 8581.485110893367, 8558.315334773328, 8575.699850505202, 8639.524826134622, 8714.956425217833, 8730.388872778845], "value": 7423.161302556016, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.3967974907353, 558.2324304081889, 578.1818726502, 578.304633392621, 592.0320276348169, 6151.354107818676, 6160.168377269068, 6223.408776166257, 6230.501559875211, 6272.623642581442, 6092.451679348857, 6100.450959413652, 6116.235470566179, 6116.785912493053, 6186.536009799261, 4819.807207711688, 4992.40091189056, 4996.675216110941, 4999.225120106371, 5063.797448102081, 4945.530174633422, 4946.703197808125, 4957.231629097496, 4968.87577810554, 4985.476524964881, 4865.68373055752, 4891.706497610137, 4894.853154405356, 4928.753562321869, 5003.099442100421, 4938.135826230413, 4938.555530002282, 4943.555080042795, 4958.826441074581, 4964.205727083649, 4507.256302710049, 4516.977415112903, 4536.206163921333, 4560.475545312298, 4574.336721175411, 6107.786227482061, 6125.499252683121, 6161.7227224775, 6187.195664563623, 6228.28374736902, 5736.397678243808, 5763.394843541451, 5766.711664416763, 5830.271216097988, 5861.238039054895, 6554.0, 6593.967030164818, 6610.289781189689, 6618.56979296349, 6635.966820165917, 4872.123017856784, 5051.924221136685, 5059.114654935408, 5068.163752980754, 5095.235714642796, 6667.4996876171435, 6693.598384096969, 6761.154855643044, 6834.2140653824945, 6912.585244885323, 5124.359455068116, 5139.820106296336, 5160.174372100446, 5181.792728290901, 5202.0, 5199.584033277329, 5243.449437808975, 5265.341832270966, 5285.471452854684, 5295.735213239323, 5753.971230143906, 5765.279340082489, 5794.5364370851075, 5811.79658711943, 5844.707764611752, 6595.835104122435, 6624.436922861611, 6624.668766561653, 6664.933350666528, 6693.866122677519, 1810.646923849863, 1817.7818661760673, 1819.281383853397, 1823.553229458791, 1831.5146486181172, 1236.3076677060874, 1273.796192609178, 1282.9550965716342, 1284.26154960896, 1285.64644722702, 6017.488513476316, 6104.694765261719, 6120.296165940974, 6158.199434073542, 6181.598196117328, 6397.168368112116, 6445.355464453517, 6518.5881223947445, 6600.60396376219, 6691.7323307068145, 5960.433758792908, 6064.636121832704, 6067.605605635619, 6095.786647467317, 6118.877622447526, 3443.1564266754303, 3463.7055850252505, 3494.4583589543663, 3509.5262139611336, 3515.191505953645, 6880.8967865482055, 6897.896531552031, 6904.620245886525, 6915.757948471779, 6945.742633644086, 6780.796576102724, 6851.9657401712675, 6852.760153394607, 6915.757948471779, 6933.583984960918, 6779.796606101825, 6871.828204294832, 6876.830938740426, 6984.126984127086, 7030.137013691478, 6311.274203466476, 6450.967745161432, 6483.805485835433, 6611.438027767694, 6636.0, 6403.295637479967, 6484.351564843667, 6533.836654083686, 6593.901091483727, 6703.4972377070935, 6910.963355496716, 6943.51395402317, 6953.721851125898, 6991.650417479004, 7005.474589405917, 5122.231665250242, 5142.408623008402, 5148.279240906351, 5156.767945442539, 5193.558547523503, 6292.790676754797, 6413.615183089029, 6440.388163124496, 6557.573757705733, 6667.233268174222, 6177.84555386119, 6277.811665650038, 6322.178116844781, 6364.268109167505, 6406.4234218918655, 6228.22147231596, 6338.651374174465, 6345.1434056401795, 6383.808485745435, 6411.198600174978, 6232.906506402498, 6284.4344009039405, 6300.716467759054, 6331.746730130743, 6332.511859712871, 6487.708053137714, 6504.479641628753, 6520.18497687789, 6549.279579246184, 6549.312322206099, 6199.938000619936, 6223.315435302204, 6283.5287353449585, 6295.874082518416, 6321.241451025906], "value": 5112.466114484631, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [931.9068093190682, 968.2737946540094, 976.4287891583423, 992.9354591951522, 993.8868467316606, 23220.65169022466, 23870.641940370908, 25135.120270790525, 25194.244172674804, 25258.621120683205, 22368.88815555924, 23026.19408320709, 23035.545721977225, 23116.30651080466, 23157.76842231577, 1050.1336397472078, 1065.2649671726522, 1070.1813112968584, 1072.1744256922166, 1081.3944191252883, 959.1559427703611, 1060.6817954613584, 1067.690369792763, 1073.1736562846604, 1083.2254937719529, 1136.0286954653734, 1139.880312567181, 1140.9144314176428, 1148.4602236948663, 1149.3391300002531, 1129.1192869561755, 1134.982975255372, 1136.7158210447387, 1138.760860219355, 1140.686311264409, 827.7640872351351, 833.8749187621845, 837.1293854391425, 838.2204549768712, 843.4770442325797, 17776.644467110586, 19995.500112497157, 20147.0, 20515.692264616042, 20658.793412065836, 20344.084516196806, 20628.793712062838, 21068.473288167766, 21086.261980830597, 21154.259600913894, 19016.904915475472, 19201.135948882333, 19225.13486893093, 19253.22987080515, 19256.711149332772, 3881.7670939743425, 3909.58949310324, 3916.5495967963766, 3920.627540383659, 3983.4423180754716, 160536.0, 160948.0, 161333.0, 164144.17927910402, 164487.3551264484, 17936.19287132056, 18151.273949042155, 18250.634987300444, 18314.725279120823, 18417.81582184188, 34410.0, 34532.827335863665, 34709.65290347115, 34781.30437391238, 34785.0, 9264.67573634933, 9296.8605470918, 9330.113639204264, 9330.440173589606, 9411.294152938557, 4435.800388982503, 4485.551444855553, 4501.032278060233, 4528.343390208402, 4536.296873984537, 6151.200343955257, 6236.6258024517765, 6275.937240627535, 6312.589681670676, 6318.083877837687, 1029.4183786160795, 1069.5240617924949, 1073.6671631794168, 1081.1620993729923, 1082.7726177502655, 708.5854774956605, 727.8798998165349, 733.1349008170411, 753.5666991479922, 755.7279379423403, 12547.372631368578, 13095.476180952846, 13214.537491187762, 14070.929645351707, 14316.928415357857, 680.9080774095434, 691.7233106757337, 700.2472342232121, 702.367868917978, 710.9939435698532, 6160.229971253593, 6253.155823963707, 6324.494040476751, 6327.493800496041, 6360.809175724736, 137871.0, 138892.0, 142495.2875235617, 142641.0, 142963.2851835755, 126924.36537817252, 127323.0, 129092.0, 131977.0, 132169.33915330362, 112455.0, 113079.4346028281, 114647.42676286733, 116337.8366216344, 117845.0, 11768.411579421167, 11789.879961403652, 11886.762264754829, 11983.34091624969, 12150.21023633461, 52356.21465678094, 53329.20006199832, 53683.463165367844, 54513.454865452426, 54774.0, 67201.0, 69038.30961690319, 69296.6535167321, 70644.64677676784, 70984.64507677428, 15253.0, 15554.766678500058, 15607.297671604578, 15693.843061569238, 15785.0, 18721.438356849318, 18902.43292701221, 18946.336878209197, 19149.712754308417, 19206.327778528244, 16103.355865765237, 16142.354305827635, 16147.19264036817, 16328.265228065004, 16420.507384778477, 16834.57913552122, 16835.1582420876, 16869.746953795457, 17028.48914532566, 17208.0, 16012.919935400249, 16095.75856362179, 16179.43371981975, 16269.0, 16315.836841631432, 12155.696107597381, 12653.74692506163, 12673.746525069262, 12749.681257968625, 12998.155119917554, 13067.477300908238, 13087.934560327518, 13130.0, 13142.211467311992, 13241.404136814059], "value": 9824.881989778514, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1384.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 106.0, 106.0, 107.0, 106.0, 109.0, 108.0, 105.0, 107.0, 106.0], "value": 106.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 117.0, 112.0, 113.0, 113.0, 115.0, 113.0, 111.0, 142.0, 114.0], "value": 116.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 165.0, 166.0, 164.0, 164.0, 164.0, 163.0, 171.0, 172.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 74.0, 74.0, 77.0, 73.0, 74.0, 75.0, 74.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 78.0, 78.0, 77.0, 77.0, 77.0, 77.0, 77.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 114.0, 113.0, 115.0, 116.0, 114.0, 115.0, 114.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 152.0, 150.0, 151.0, 154.0, 151.0, 150.0, 153.0, 152.0], "value": 151.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 95.0, 94.0, 96.0, 94.0, 94.0, 94.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 44.0, 54.0, 47.0, 45.0, 47.0, 49.0, 46.0, 44.0, 44.0], "value": 46.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 39.0, 38.0, 38.0, 37.0, 39.0, 37.0, 41.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 71.0, 74.0, 73.0, 72.0, 73.0, 71.0, 71.0, 80.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 107.0, 108.0, 110.0, 108.0, 111.0, 105.0, 108.0, 107.0], "value": 108.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 153.0, 159.0, 152.0, 152.0, 154.0, 157.0, 161.0, 153.0, 155.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 60.0, 62.0, 61.0, 60.0, 58.0, 59.0, 61.0, 62.0], "value": 60.5, "unit": "score"}]}]} 12:30:52 INFO - Return code: 0 12:30:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:30:52 INFO - # TBPL SUCCESS # 12:30:52 INFO - Running post-action listener: _package_coverage_data 12:30:52 INFO - Running post-action listener: _resource_record_post_action 12:30:52 INFO - [mozharness: 2017-09-25 19:30:52.130106Z] Finished run-tests step (success) 12:30:52 INFO - Running post-run listener: _resource_record_post_run 12:30:52 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 331235328; Read time: 12; Write time: 260508 12:30:52 INFO - TinderboxPrint: CPU usage
13.1% 12:30:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:30:52 INFO - TinderboxPrint: I/O write bytes / time
331,235,328 / 260,508 12:30:52 INFO - TinderboxPrint: CPU idle
8,212.7 (86.9%) 12:30:52 INFO - TinderboxPrint: CPU user
1,189.7 (12.6%) 12:30:52 INFO - TinderboxPrint: Swap in / out
0 / 0 12:30:52 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12648448; Read time: 0; Write time: 1164 12:30:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:30:52 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 317562880; Read time: 12; Write time: 259312 12:30:52 INFO - Running post-run listener: _upload_blobber_files 12:30:52 INFO - Blob upload gear active. 12:30:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:30:52 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 12:30:52 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'] 12:30:52 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 12:30:53 INFO - (blobuploader) - INFO - Open directory for files ... 12:30:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:30:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:30:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:54 INFO - (blobuploader) - INFO - Done attempting. 12:30:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:30:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:30:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:30:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:30:55 INFO - (blobuploader) - INFO - Done attempting. 12:30:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:30:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:30:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:31:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:02 INFO - (blobuploader) - INFO - Done attempting. 12:31:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:31:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:31:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:03 INFO - (blobuploader) - INFO - Done attempting. 12:31:03 INFO - (blobuploader) - INFO - Iteration through files over. 12:31:03 INFO - Return code: 0 12:31:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:31:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:31:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee3b455a4cec8d2630096e1c237a2918551ba87966894405b8739f6cb1d08bc70357e710596f0e1f3575eb462789f9c8f3efb389b35d5d7ab964d7f2c1f020da", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67b7658e32ace9743e06fb54fbcb1f9b9b32a5200ba231b7160de83e6de337e88ab77a3bcc35160920dc2447f718d7aa820ab67c14c84b89845547557c687eb4", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45a96130836bc73524ed01bc2a3941baacddc1588838e3e85c1030ce40a5835948fe356412fe91a84f796e1ca1aa98bef260e62a847f4346e7443e7d208b9f76", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a0eee57a741297fc79012e1ccafba8b6d86cdd66bb8e68130d61ec2da757aaa425d224e7953c24e4a3aa4d99140261658b13fe0bf3b5ab6b44c2ce25b850bbc"} 12:31:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:31:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:31:03 INFO - Contents: 12:31:03 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee3b455a4cec8d2630096e1c237a2918551ba87966894405b8739f6cb1d08bc70357e710596f0e1f3575eb462789f9c8f3efb389b35d5d7ab964d7f2c1f020da", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67b7658e32ace9743e06fb54fbcb1f9b9b32a5200ba231b7160de83e6de337e88ab77a3bcc35160920dc2447f718d7aa820ab67c14c84b89845547557c687eb4", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45a96130836bc73524ed01bc2a3941baacddc1588838e3e85c1030ce40a5835948fe356412fe91a84f796e1ca1aa98bef260e62a847f4346e7443e7d208b9f76", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a0eee57a741297fc79012e1ccafba8b6d86cdd66bb8e68130d61ec2da757aaa425d224e7953c24e4a3aa4d99140261658b13fe0bf3b5ab6b44c2ce25b850bbc"} 12:31:03 INFO - Running post-run listener: copy_logs_to_upload_dir 12:31:03 INFO - Copying logs to upload dir... 12:31:03 INFO - mkdir: /builds/slave/test/build/upload/logs 12:31:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.128151 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-25 12:31:03.465137) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 12:31:03.468308) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053 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/ee3b455a4cec8d2630096e1c237a2918551ba87966894405b8739f6cb1d08bc70357e710596f0e1f3575eb462789f9c8f3efb389b35d5d7ab964d7f2c1f020da", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67b7658e32ace9743e06fb54fbcb1f9b9b32a5200ba231b7160de83e6de337e88ab77a3bcc35160920dc2447f718d7aa820ab67c14c84b89845547557c687eb4", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45a96130836bc73524ed01bc2a3941baacddc1588838e3e85c1030ce40a5835948fe356412fe91a84f796e1ca1aa98bef260e62a847f4346e7443e7d208b9f76", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a0eee57a741297fc79012e1ccafba8b6d86cdd66bb8e68130d61ec2da757aaa425d224e7953c24e4a3aa4d99140261658b13fe0bf3b5ab6b44c2ce25b850bbc"} build_url:https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011457 build_url: 'https://queue.taskcluster.net/v1/task/NWLEEd7oRfGpMvp2N6mJHQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ee3b455a4cec8d2630096e1c237a2918551ba87966894405b8739f6cb1d08bc70357e710596f0e1f3575eb462789f9c8f3efb389b35d5d7ab964d7f2c1f020da", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/67b7658e32ace9743e06fb54fbcb1f9b9b32a5200ba231b7160de83e6de337e88ab77a3bcc35160920dc2447f718d7aa820ab67c14c84b89845547557c687eb4", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45a96130836bc73524ed01bc2a3941baacddc1588838e3e85c1030ce40a5835948fe356412fe91a84f796e1ca1aa98bef260e62a847f4346e7443e7d208b9f76", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2a0eee57a741297fc79012e1ccafba8b6d86cdd66bb8e68130d61ec2da757aaa425d224e7953c24e4a3aa4d99140261658b13fe0bf3b5ab6b44c2ce25b850bbc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 12:31:03.500099) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 12:31:03.500483) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1506366625.344531-240443053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 12:31:03.581294) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 12:31:03.581635) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 12:31:03.581986) ========= ========= Total master_lag: 0.21 =========