builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-074 starttime: 1504652117.5 results: success (0) revision: 978d2539a8d1a49e9f9705204f3918772b337547 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:17.501141) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:17.501501) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:17.501761) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:17.518996) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:17.519266) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 15:55:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 38.6M=0s 2017-09-05 15:55:17 (38.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137782 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:17.672997) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:17.676307) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019267 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:17.726515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 15:55:17.726798) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 978d2539a8d1a49e9f9705204f3918772b337547 --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 978d2539a8d1a49e9f9705204f3918772b337547 --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=23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 15:55:18,002 truncating revision to first 12 chars 2017-09-05 15:55:18,002 Setting DEBUG logging. 2017-09-05 15:55:18,002 attempt 1/10 2017-09-05 15:55:18,002 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/978d2539a8d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-05 15:55:18,766 unpacking tar archive at: mozilla-central-978d2539a8d1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.100797 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-05 15:55:18.849255) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:18.849550) ========= script_repo_revision: 978d2539a8d1a49e9f9705204f3918772b337547 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:18.849919) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:18.850192) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 15:55:18.863246) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-09-05 15:55:18.863502) ========= /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=23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:55:19 INFO - MultiFileLogger online at 20170905 15:55:19 in /builds/slave/test 15:55:19 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 15:55:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:55:19 INFO - {'append_to_log': False, 15:55:19 INFO - 'base_work_dir': '/builds/slave/test', 15:55:19 INFO - 'blob_upload_branch': 'Firefox', 15:55:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:55:19 INFO - 'branch': 'Firefox', 15:55:19 INFO - 'buildbot_json_path': 'buildprops.json', 15:55:19 INFO - 'code_coverage': False, 15:55:19 INFO - 'config_files': ('talos/linux_config.py',), 15:55:19 INFO - 'default_actions': ('clobber', 15:55:19 INFO - 'read-buildbot-config', 15:55:19 INFO - 'download-and-extract', 15:55:19 INFO - 'populate-webroot', 15:55:19 INFO - 'create-virtualenv', 15:55:19 INFO - 'install', 15:55:19 INFO - 'setup-mitmproxy', 15:55:19 INFO - 'run-tests'), 15:55:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:55:19 INFO - 'disable_ccov_upload': False, 15:55:19 INFO - 'disable_stylo': False, 15:55:19 INFO - 'download_minidump_stackwalk': True, 15:55:19 INFO - 'download_symbols': 'ondemand', 15:55:19 INFO - 'enable_stylo': False, 15:55:19 INFO - 'enable_webrender': False, 15:55:19 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:55:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:55:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:55:19 INFO - 'gecko_profile': False, 15:55:19 INFO - 'gecko_profile_interval': 0, 15:55:19 INFO - 'installer_path': 'installer.exe', 15:55:19 INFO - 'log_level': 'info', 15:55:19 INFO - 'log_name': 'talos', 15:55:19 INFO - 'log_to_console': True, 15:55:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:55:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:55:19 INFO - 'opt_config_files': (), 15:55:19 INFO - 'pip_index': False, 15:55:19 INFO - 'suite': 'dromaeojs-e10s', 15:55:19 INFO - 'system_bits': '32', 15:55:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:55:19 INFO - 'title': 'talos-linux64-ix-074', 15:55:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:55:19 INFO - 'use_talos_json': True, 15:55:19 INFO - 'verify': 'False', 15:55:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:55:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:55:19 INFO - 'work_dir': 'build'} 15:55:19 INFO - [mozharness: 2017-09-05 22:55:19.280348Z] Running clobber step. 15:55:19 INFO - Running pre-action listener: _resource_record_pre_action 15:55:19 INFO - Running main action method: clobber 15:55:19 INFO - rmtree: /builds/slave/test/build 15:55:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:55:20 INFO - Running post-action listener: _resource_record_post_action 15:55:20 INFO - [mozharness: 2017-09-05 22:55:20.818510Z] Finished clobber step (success) 15:55:20 INFO - [mozharness: 2017-09-05 22:55:20.818609Z] Running read-buildbot-config step. 15:55:20 INFO - Running pre-action listener: _resource_record_pre_action 15:55:20 INFO - Running main action method: read_buildbot_config 15:55:20 INFO - Using buildbot properties: 15:55:20 INFO - { 15:55:20 INFO - "project": "", 15:55:20 INFO - "product": "firefox", 15:55:20 INFO - "who": "archaeopteryx@coole-files.de", 15:55:20 INFO - "installer_path": "public/build/target.tar.bz2", 15:55:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 15:55:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:55:20 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 15:55:20 INFO - "stage_platform": "linux64-stylosequential", 15:55:20 INFO - "basedir": "/builds/slave/test", 15:55:20 INFO - "buildnumber": 60, 15:55:20 INFO - "platform": "ubuntu64_hw_styloseq", 15:55:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:55:20 INFO - "slavebuilddir": "test", 15:55:20 INFO - "taskId": "Ze3gJgm_RFeW787ZxDdOMw", 15:55:20 INFO - "branch": "mozilla-central", 15:55:20 INFO - "script_repo_revision": "production", 15:55:20 INFO - "revision": "978d2539a8d1a49e9f9705204f3918772b337547", 15:55:20 INFO - "slavename": "talos-linux64-ix-074", 15:55:20 INFO - "repo_path": "mozilla-central" 15:55:20 INFO - } 15:55:20 INFO - Finding installer, test and symbols from parent task. 15:55:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ze3gJgm_RFeW787ZxDdOMw'}, attempt #1 15:55:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ze3gJgm_RFeW787ZxDdOMw'}, attempt #1 15:55:21 INFO - Task dependencies: ciQLVfAyTVS5ODxEPFePfg 15:55:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg'}, attempt #1 15:55:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 15:55:22 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.test_packages.json 15:55:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.crashreporter-symbols.zip 15:55:22 INFO - Running post-action listener: _resource_record_post_action 15:55:22 INFO - [mozharness: 2017-09-05 22:55:22.202745Z] Finished read-buildbot-config step (success) 15:55:22 INFO - [mozharness: 2017-09-05 22:55:22.202963Z] Running download-and-extract step. 15:55:22 INFO - Running pre-action listener: _resource_record_pre_action 15:55:22 INFO - Running main action method: download_and_extract 15:55:22 INFO - mkdir: /builds/slave/test/build/tests 15:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:22 INFO - trying https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.test_packages.json 15:55:22 INFO - Downloading https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:55:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:55:22 INFO - Downloaded 989 bytes. 15:55:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:55:22 INFO - Using the following test package requirements: 15:55:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:55:22 INFO - u'common': [u'target.common.tests.zip'], 15:55:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:55:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:55:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:55:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:55:22 INFO - u'mozbase': [u'target.common.tests.zip'], 15:55:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:55:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:55:22 INFO - u'web-platform': [u'target.common.tests.zip', 15:55:22 INFO - u'target.web-platform.tests.tar.gz'], 15:55:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:55:22 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:55:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.common.tests.zip 15:55:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:55:22 INFO - Fetch https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.common.tests.zip into memory 15:55:23 INFO - Content-Length response header: 55019957 15:55:23 INFO - Bytes received: 55019957 15:55:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:55:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.common.tests.zip 15:55:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:55:34 INFO - Fetch https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.common.tests.zip into memory 15:55:35 INFO - Content-Length response header: 55019957 15:55:35 INFO - Bytes received: 55019957 15:55:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.talos.tests.zip 15:55:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:55:48 INFO - Fetch https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.talos.tests.zip into memory 15:55:48 INFO - Content-Length response header: 14496654 15:55:48 INFO - Bytes received: 14496654 15:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:49 INFO - trying https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 15:55:49 INFO - Downloading https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:55:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:55:50 INFO - Downloaded 62666628 bytes. 15:55:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 15:55:50 INFO - mkdir: /builds/slave/test/properties 15:55:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:55:50 INFO - Writing to file /builds/slave/test/properties/build_url 15:55:50 INFO - Contents: 15:55:50 INFO - build_url:https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 15:55:50 INFO - Running post-action listener: _resource_record_post_action 15:55:50 INFO - Running post-action listener: find_tests_for_verification 15:55:50 INFO - Running post-action listener: set_extra_try_arguments 15:55:50 INFO - [mozharness: 2017-09-05 22:55:50.121153Z] Finished download-and-extract step (success) 15:55:50 INFO - [mozharness: 2017-09-05 22:55:50.121255Z] Running populate-webroot step. 15:55:50 INFO - Running pre-action listener: _resource_record_pre_action 15:55:50 INFO - Running main action method: populate_webroot 15:55:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:55:50 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:55:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:55:50 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:55:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:55:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:50 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:50 INFO - 'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:55:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:55:50 INFO - 'tests': ['damp', 'tps']}, 15:55:50 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:50 INFO - 'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['damp', 'tps']}, 15:55:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:55:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:55:50 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:55:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:55:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:55:50 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:50 INFO - 'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:55:50 INFO - 'other-e10s': {'tests': ['a11yr', 15:55:50 INFO - 'ts_paint', 15:55:50 INFO - 'tpaint', 15:55:50 INFO - 'sessionrestore', 15:55:50 INFO - 'sessionrestore_many_windows', 15:55:50 INFO - 'sessionrestore_no_auto_restore', 15:55:50 INFO - 'tabpaint']}, 15:55:50 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['a11yr', 15:55:50 INFO - 'ts_paint', 15:55:50 INFO - 'tpaint', 15:55:50 INFO - 'sessionrestore', 15:55:50 INFO - 'sessionrestore_many_windows', 15:55:50 INFO - 'sessionrestore_no_auto_restore', 15:55:50 INFO - 'tabpaint']}, 15:55:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:55:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:55:50 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['bloom_basic_singleton']}, 15:55:50 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['bloom_basic']}, 15:55:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:55:50 INFO - 'tsvgr_opacity', 15:55:50 INFO - 'tart', 15:55:50 INFO - 'tscrollx', 15:55:50 INFO - 'tsvg_static']}, 15:55:50 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['tsvgx', 15:55:50 INFO - 'tsvgr_opacity', 15:55:50 INFO - 'tart', 15:55:50 INFO - 'tscrollx', 15:55:50 INFO - 'tsvg_static']}, 15:55:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:55:50 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:50 INFO - 'talos_options': ['--enable-stylo'], 15:55:50 INFO - 'tests': ['tp5o']}, 15:55:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:55:50 INFO - 'talos_options': ['--mitmproxy', 15:55:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:55:50 INFO - '--firstNonBlankPaint'], 15:55:50 INFO - 'tests': ['tp6_google', 15:55:50 INFO - 'tp6_youtube', 15:55:50 INFO - 'tp6_amazon', 15:55:50 INFO - 'tp6_facebook']}, 15:55:50 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:55:50 INFO - 'talos_options': ['--enable-stylo', 15:55:50 INFO - '--mitmproxy', 15:55:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:55:50 INFO - '--firstNonBlankPaint'], 15:55:50 INFO - 'tests': ['tp6_google', 15:55:50 INFO - 'tp6_youtube', 15:55:50 INFO - 'tp6_amazon', 15:55:50 INFO - 'tp6_facebook']}, 15:55:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:55:50 INFO - 'talos_options': ['--enable-stylo', 15:55:50 INFO - '--stylo-threads=1', 15:55:50 INFO - '--mitmproxy', 15:55:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:55:50 INFO - '--firstNonBlankPaint'], 15:55:50 INFO - 'tests': ['tp6_google', 15:55:50 INFO - 'tp6_youtube', 15:55:50 INFO - 'tp6_amazon', 15:55:50 INFO - 'tp6_facebook']}, 15:55:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:55:50 INFO - 'talos_options': ['--xperf_path', 15:55:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:50 INFO - 'tests': ['tp5n']}, 15:55:50 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:55:50 INFO - 'talos_options': ['--enable-stylo', 15:55:50 INFO - '--xperf_path', 15:55:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:55:50 INFO - 'tests': ['tp5n']}}} 15:55:50 INFO - Running post-action listener: _resource_record_post_action 15:55:50 INFO - [mozharness: 2017-09-05 22:55:50.135565Z] Finished populate-webroot step (success) 15:55:50 INFO - [mozharness: 2017-09-05 22:55:50.135655Z] Running create-virtualenv step. 15:55:50 INFO - Running pre-action listener: _resource_record_pre_action 15:55:50 INFO - Running main action method: create_virtualenv 15:55:50 INFO - Creating virtualenv /builds/slave/test/build/venv 15:55:50 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:55:50 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:55:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:55:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:55:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:55:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:55:51 INFO - Installing pip...............done. 15:55:51 INFO - Return code: 0 15:55:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:55:51 INFO - Reading from file tmpfile_stdout 15:55:51 INFO - Output received: 15:55:51 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:55:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:55: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')]} 15:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55: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')]} 15:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:55: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', 'pip>=1.5'] in /builds/slave/test/build 15:55: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 pip>=1.5 15:55:51 INFO - Using env: {'DISPLAY': ':0', 15:55:51 INFO - 'HOME': '/home/cltbld', 15:55:51 INFO - 'LANG': 'en_US.UTF-8', 15:55:51 INFO - 'LANGUAGE': 'en_US:en', 15:55:51 INFO - 'LOGNAME': 'cltbld', 15:55:51 INFO - 'MAIL': '/var/mail/cltbld', 15:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:51 INFO - 'MOZ_NO_REMOTE': '1', 15:55:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:51 INFO - 'NO_EM_RESTART': '1', 15:55:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:51 INFO - 'PWD': '/builds/slave/test', 15:55:51 INFO - 'SHELL': '/bin/bash', 15:55:51 INFO - 'SHLVL': '1', 15:55:51 INFO - 'TERM': 'linux', 15:55:51 INFO - 'TMOUT': '86400', 15:55:51 INFO - 'USER': 'cltbld', 15:55:51 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:51 INFO - '_': '/tools/buildbot/bin/python'} 15:55:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:55:52 INFO - Downloading/unpacking pip>=1.5 15:55:52 INFO - Running setup.py egg_info for package pip 15:55:52 INFO - warning: no files found matching 'pip/cacert.pem' 15:55:52 INFO - warning: no files found matching '*.html' under directory 'docs' 15:55:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:55:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:55:52 INFO - Installing collected packages: pip 15:55:52 INFO - Found existing installation: pip 1.1 15:55:52 INFO - Uninstalling pip: 15:55:52 INFO - Successfully uninstalled pip 15:55:52 INFO - Running setup.py install for pip 15:55:52 INFO - warning: no files found matching 'pip/cacert.pem' 15:55:52 INFO - warning: no files found matching '*.html' under directory 'docs' 15:55:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:55:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:55:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:55:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:55:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:55:52 INFO - Successfully installed pip 15:55:52 INFO - Cleaning up... 15:55:52 INFO - Return code: 0 15:55:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:55:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:55:52 INFO - Using env: {'DISPLAY': ':0', 15:55:52 INFO - 'HOME': '/home/cltbld', 15:55:52 INFO - 'LANG': 'en_US.UTF-8', 15:55:52 INFO - 'LANGUAGE': 'en_US:en', 15:55:52 INFO - 'LOGNAME': 'cltbld', 15:55:52 INFO - 'MAIL': '/var/mail/cltbld', 15:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:52 INFO - 'MOZ_NO_REMOTE': '1', 15:55:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:52 INFO - 'NO_EM_RESTART': '1', 15:55:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:52 INFO - 'PWD': '/builds/slave/test', 15:55:52 INFO - 'SHELL': '/bin/bash', 15:55:52 INFO - 'SHLVL': '1', 15:55:52 INFO - 'TERM': 'linux', 15:55:52 INFO - 'TMOUT': '86400', 15:55:52 INFO - 'USER': 'cltbld', 15:55:52 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:52 INFO - '_': '/tools/buildbot/bin/python'} 15:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:52 INFO - Downloading/unpacking psutil>=3.1.1 15:55: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 15:55: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 15:55: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 15:55: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 15:55:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:55:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:53 INFO - Installing collected packages: psutil 15:55:53 INFO - Running setup.py install for psutil 15:55:53 INFO - building 'psutil._psutil_linux' extension 15:55:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:55:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:55:54 INFO - building 'psutil._psutil_posix' extension 15:55:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:55:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:55:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:55:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:55:54 INFO - Successfully installed psutil 15:55:54 INFO - Cleaning up... 15:55:54 INFO - Return code: 0 15:55:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:55:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:55:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:55:54 INFO - Using env: {'DISPLAY': ':0', 15:55:54 INFO - 'HOME': '/home/cltbld', 15:55:54 INFO - 'LANG': 'en_US.UTF-8', 15:55:54 INFO - 'LANGUAGE': 'en_US:en', 15:55:54 INFO - 'LOGNAME': 'cltbld', 15:55:54 INFO - 'MAIL': '/var/mail/cltbld', 15:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:54 INFO - 'MOZ_NO_REMOTE': '1', 15:55:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:54 INFO - 'NO_EM_RESTART': '1', 15:55:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:54 INFO - 'PWD': '/builds/slave/test', 15:55:54 INFO - 'SHELL': '/bin/bash', 15:55:54 INFO - 'SHLVL': '1', 15:55:54 INFO - 'TERM': 'linux', 15:55:54 INFO - 'TMOUT': '86400', 15:55:54 INFO - 'USER': 'cltbld', 15:55:54 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:54 INFO - '_': '/tools/buildbot/bin/python'} 15:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:55: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 15:55: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 15:55: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 15:55: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 15:55:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:55:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:55:55 INFO - Installing collected packages: mozsystemmonitor 15:55:55 INFO - Running setup.py install for mozsystemmonitor 15:55:55 INFO - Successfully installed mozsystemmonitor 15:55:55 INFO - Cleaning up... 15:55:55 INFO - Return code: 0 15:55:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:55 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 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:55:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:55:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:55:55 INFO - Using env: {'DISPLAY': ':0', 15:55:55 INFO - 'HOME': '/home/cltbld', 15:55:55 INFO - 'LANG': 'en_US.UTF-8', 15:55:55 INFO - 'LANGUAGE': 'en_US:en', 15:55:55 INFO - 'LOGNAME': 'cltbld', 15:55:55 INFO - 'MAIL': '/var/mail/cltbld', 15:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:55 INFO - 'MOZ_NO_REMOTE': '1', 15:55:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:55 INFO - 'NO_EM_RESTART': '1', 15:55:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:55 INFO - 'PWD': '/builds/slave/test', 15:55:55 INFO - 'SHELL': '/bin/bash', 15:55:55 INFO - 'SHLVL': '1', 15:55:55 INFO - 'TERM': 'linux', 15:55:55 INFO - 'TMOUT': '86400', 15:55:55 INFO - 'USER': 'cltbld', 15:55:55 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:55 INFO - '_': '/tools/buildbot/bin/python'} 15:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:56 INFO - Downloading/unpacking jsonschema==2.5.1 15:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:55:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:57 INFO - Downloading functools32-3.2.3-2.tar.gz 15:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:55:57 INFO - warning: no files found matching '*.txt' 15:55:57 INFO - no previously-included directories found matching 'build' 15:55:57 INFO - no previously-included directories found matching 'dist' 15:55:57 INFO - no previously-included directories found matching '.git*' 15:55:57 INFO - Installing collected packages: jsonschema, functools32 15:55:57 INFO - Running setup.py install for functools32 15:55:57 INFO - warning: no files found matching '*.txt' 15:55:57 INFO - no previously-included directories found matching 'build' 15:55:57 INFO - no previously-included directories found matching 'dist' 15:55:57 INFO - no previously-included directories found matching '.git*' 15:55:57 INFO - Successfully installed jsonschema functools32 15:55:57 INFO - Cleaning up... 15:55:57 INFO - Return code: 0 15:55:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:55:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:55:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:55:57 INFO - Using env: {'DISPLAY': ':0', 15:55:57 INFO - 'HOME': '/home/cltbld', 15:55:57 INFO - 'LANG': 'en_US.UTF-8', 15:55:57 INFO - 'LANGUAGE': 'en_US:en', 15:55:57 INFO - 'LOGNAME': 'cltbld', 15:55:57 INFO - 'MAIL': '/var/mail/cltbld', 15:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:57 INFO - 'MOZ_NO_REMOTE': '1', 15:55:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:57 INFO - 'NO_EM_RESTART': '1', 15:55:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:57 INFO - 'PWD': '/builds/slave/test', 15:55:57 INFO - 'SHELL': '/bin/bash', 15:55:57 INFO - 'SHLVL': '1', 15:55:57 INFO - 'TERM': 'linux', 15:55:57 INFO - 'TMOUT': '86400', 15:55:57 INFO - 'USER': 'cltbld', 15:55:57 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:57 INFO - '_': '/tools/buildbot/bin/python'} 15:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:55:57 INFO - Cleaning up... 15:55:57 INFO - Return code: 0 15:55:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:55:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:55:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:55:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:55:57 INFO - Using env: {'DISPLAY': ':0', 15:55:57 INFO - 'HOME': '/home/cltbld', 15:55:57 INFO - 'LANG': 'en_US.UTF-8', 15:55:57 INFO - 'LANGUAGE': 'en_US:en', 15:55:57 INFO - 'LOGNAME': 'cltbld', 15:55:57 INFO - 'MAIL': '/var/mail/cltbld', 15:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:55:57 INFO - 'MOZ_NO_REMOTE': '1', 15:55:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:55:57 INFO - 'NO_EM_RESTART': '1', 15:55:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:55:57 INFO - 'PWD': '/builds/slave/test', 15:55:57 INFO - 'SHELL': '/bin/bash', 15:55:57 INFO - 'SHLVL': '1', 15:55:57 INFO - 'TERM': 'linux', 15:55:57 INFO - 'TMOUT': '86400', 15:55:57 INFO - 'USER': 'cltbld', 15:55:57 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:55:57 INFO - '_': '/tools/buildbot/bin/python'} 15:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:55:57 INFO - Downloading/unpacking blobuploader==1.2.4 15:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:55:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:02 INFO - Downloading blobuploader-1.2.4.tar.gz 15:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:56:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:56: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 15:56: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 15:56: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 15:56: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 15:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:56:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:56: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 15:56: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 15:56: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 15:56: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 15:56:02 INFO - Downloading docopt-0.6.1.tar.gz 15:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:56:02 INFO - Installing collected packages: blobuploader, requests, docopt 15:56:02 INFO - Running setup.py install for blobuploader 15:56:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:56:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:56:02 INFO - Running setup.py install for requests 15:56:03 INFO - Running setup.py install for docopt 15:56:03 INFO - Successfully installed blobuploader requests docopt 15:56:03 INFO - Cleaning up... 15:56:03 INFO - Return code: 0 15:56:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:03 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 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:56:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:56:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:56:03 INFO - Using env: {'DISPLAY': ':0', 15:56:03 INFO - 'HOME': '/home/cltbld', 15:56:03 INFO - 'LANG': 'en_US.UTF-8', 15:56:03 INFO - 'LANGUAGE': 'en_US:en', 15:56:03 INFO - 'LOGNAME': 'cltbld', 15:56:03 INFO - 'MAIL': '/var/mail/cltbld', 15:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:03 INFO - 'MOZ_NO_REMOTE': '1', 15:56:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:03 INFO - 'NO_EM_RESTART': '1', 15:56:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:03 INFO - 'PWD': '/builds/slave/test', 15:56:03 INFO - 'SHELL': '/bin/bash', 15:56:03 INFO - 'SHLVL': '1', 15:56:03 INFO - 'TERM': 'linux', 15:56:03 INFO - 'TMOUT': '86400', 15:56:03 INFO - 'USER': 'cltbld', 15:56:03 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:03 INFO - '_': '/tools/buildbot/bin/python'} 15:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:56:03 INFO - Running setup.py (path:/tmp/pip-tklqeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:56:03 INFO - Running setup.py (path:/tmp/pip-0aAROD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:56:03 INFO - Running setup.py (path:/tmp/pip-mFyX4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:56:03 INFO - Running setup.py (path:/tmp/pip-11uKWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:56:03 INFO - Running setup.py (path:/tmp/pip-7fhGUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:56:03 INFO - Running setup.py (path:/tmp/pip-Al_BJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:56:03 INFO - Running setup.py (path:/tmp/pip-bi1xMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:56:04 INFO - Running setup.py (path:/tmp/pip-5V6rPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:56:04 INFO - Running setup.py (path:/tmp/pip-BrBVng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:56:04 INFO - Running setup.py (path:/tmp/pip-5Qt7wy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:56:04 INFO - Running setup.py (path:/tmp/pip-LnHf2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:56:04 INFO - Running setup.py (path:/tmp/pip-aDyagb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:56:04 INFO - Running setup.py (path:/tmp/pip-wF4El7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:56:04 INFO - Running setup.py (path:/tmp/pip-Gny02w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:04 INFO - Running setup.py (path:/tmp/pip-yT5Jzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:56:04 INFO - Running setup.py (path:/tmp/pip-abPfHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:56:04 INFO - Running setup.py (path:/tmp/pip-0RwtSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:56:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:56:04 INFO - Running setup.py install for manifestparser 15:56:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:56:04 INFO - Running setup.py install for mozcrash 15:56:04 INFO - Running setup.py install for mozdebug 15:56:05 INFO - Running setup.py install for mozdevice 15:56:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:56:05 INFO - Running setup.py install for mozfile 15:56:05 INFO - Running setup.py install for mozhttpd 15:56:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:56:05 INFO - Running setup.py install for mozinfo 15:56:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:56:05 INFO - Running setup.py install for mozInstall 15:56:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:56:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:56:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:56:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:56:05 INFO - Running setup.py install for mozleak 15:56:05 INFO - Running setup.py install for mozlog 15:56:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:56:05 INFO - Running setup.py install for moznetwork 15:56:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:56:05 INFO - Running setup.py install for mozprocess 15:56:05 INFO - Running setup.py install for mozprofile 15:56:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:56:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:56:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:56:06 INFO - Running setup.py install for mozrunner 15:56:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:56:06 INFO - Running setup.py install for mozscreenshot 15:56:06 INFO - Running setup.py install for moztest 15:56:06 INFO - Running setup.py install for mozversion 15:56:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:56:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:56:06 INFO - Cleaning up... 15:56:06 INFO - Return code: 0 15:56:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:06 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 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:56:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:56:06 INFO - Using env: {'DISPLAY': ':0', 15:56:06 INFO - 'HOME': '/home/cltbld', 15:56:06 INFO - 'LANG': 'en_US.UTF-8', 15:56:06 INFO - 'LANGUAGE': 'en_US:en', 15:56:06 INFO - 'LOGNAME': 'cltbld', 15:56:06 INFO - 'MAIL': '/var/mail/cltbld', 15:56:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:06 INFO - 'MOZ_NO_REMOTE': '1', 15:56:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:06 INFO - 'NO_EM_RESTART': '1', 15:56:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:06 INFO - 'PWD': '/builds/slave/test', 15:56:06 INFO - 'SHELL': '/bin/bash', 15:56:06 INFO - 'SHLVL': '1', 15:56:06 INFO - 'TERM': 'linux', 15:56:06 INFO - 'TMOUT': '86400', 15:56:06 INFO - 'USER': 'cltbld', 15:56:06 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:56:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:06 INFO - '_': '/tools/buildbot/bin/python'} 15:56:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:56:06 INFO - Running setup.py (path:/tmp/pip-kD0ngE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:56:06 INFO - Running setup.py (path:/tmp/pip-EOcIiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:56:06 INFO - Running setup.py (path:/tmp/pip-jZIhfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:56:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:56:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:56:06 INFO - Running setup.py (path:/tmp/pip-jL4HFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:56:07 INFO - Running setup.py (path:/tmp/pip-JU_qWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:56:07 INFO - Running setup.py (path:/tmp/pip-i_1MvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:56:07 INFO - Running setup.py (path:/tmp/pip-Ia_XkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:56:07 INFO - Running setup.py (path:/tmp/pip-BiAb2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:56:07 INFO - Running setup.py (path:/tmp/pip-fxTSSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:56:07 INFO - Running setup.py (path:/tmp/pip-MUpf9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:56:07 INFO - Running setup.py (path:/tmp/pip-SwIeNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:56:07 INFO - Running setup.py (path:/tmp/pip-7wN5o9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:56:07 INFO - Running setup.py (path:/tmp/pip-fFiCy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:56:07 INFO - Running setup.py (path:/tmp/pip-dt1zFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:07 INFO - Running setup.py (path:/tmp/pip-DngWIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:56:07 INFO - Running setup.py (path:/tmp/pip-TycNiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:56:07 INFO - Running setup.py (path:/tmp/pip-1Ly3l_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:56:07 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:56:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:10 INFO - Downloading blessings-1.6.tar.gz 15:56:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:56:10 INFO - Installing collected packages: blessings 15:56:10 INFO - Running setup.py install for blessings 15:56:10 INFO - Successfully installed blessings 15:56:10 INFO - Cleaning up... 15:56:10 INFO - Return code: 0 15:56:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:56:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:56:10 INFO - Reading from file tmpfile_stdout 15:56:10 INFO - Current package versions: 15:56:10 INFO - argparse == 1.2.1 15:56:10 INFO - blessings == 1.6 15:56:10 INFO - blobuploader == 1.2.4 15:56:10 INFO - docopt == 0.6.1 15:56:10 INFO - functools32 == 3.2.3-2 15:56:10 INFO - jsonschema == 2.5.1 15:56:10 INFO - manifestparser == 1.1 15:56:10 INFO - mozInstall == 1.13 15:56:10 INFO - mozcrash == 1.0 15:56:10 INFO - mozdebug == 0.1 15:56:10 INFO - mozdevice == 0.51 15:56:10 INFO - mozfile == 1.2 15:56:10 INFO - mozhttpd == 0.7 15:56:10 INFO - mozinfo == 0.10 15:56:10 INFO - mozleak == 0.1 15:56:10 INFO - mozlog == 3.5 15:56:10 INFO - moznetwork == 0.27 15:56:10 INFO - mozprocess == 0.25 15:56:10 INFO - mozprofile == 0.28 15:56:10 INFO - mozrunner == 6.13 15:56:10 INFO - mozscreenshot == 0.1 15:56:10 INFO - mozsystemmonitor == 0.3 15:56:10 INFO - moztest == 0.8 15:56:10 INFO - mozversion == 1.4 15:56:10 INFO - psutil == 3.1.1 15:56:10 INFO - requests == 1.2.3 15:56:10 INFO - wsgiref == 0.1.2 15:56:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:10 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 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:56:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:56:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:56:10 INFO - Using env: {'DISPLAY': ':0', 15:56:10 INFO - 'HOME': '/home/cltbld', 15:56:10 INFO - 'LANG': 'en_US.UTF-8', 15:56:10 INFO - 'LANGUAGE': 'en_US:en', 15:56:10 INFO - 'LOGNAME': 'cltbld', 15:56:10 INFO - 'MAIL': '/var/mail/cltbld', 15:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:10 INFO - 'MOZ_NO_REMOTE': '1', 15:56:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:10 INFO - 'NO_EM_RESTART': '1', 15:56:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:10 INFO - 'PWD': '/builds/slave/test', 15:56:10 INFO - 'SHELL': '/bin/bash', 15:56:10 INFO - 'SHLVL': '1', 15:56:10 INFO - 'TERM': 'linux', 15:56:10 INFO - 'TMOUT': '86400', 15:56:10 INFO - 'USER': 'cltbld', 15:56:10 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:10 INFO - '_': '/tools/buildbot/bin/python'} 15:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:56:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:56:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:56:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:56:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:56:11 INFO - Installing collected packages: simplejson 15:56:11 INFO - Running setup.py install for simplejson 15:56:11 INFO - building 'simplejson._speedups' extension 15:56:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:56:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:56:12 INFO - Successfully installed simplejson 15:56:12 INFO - Cleaning up... 15:56:12 INFO - Return code: 0 15:56:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:12 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 0x296c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b35a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cd8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, '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': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:56:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:56:12 INFO - Using env: {'DISPLAY': ':0', 15:56:12 INFO - 'HOME': '/home/cltbld', 15:56:12 INFO - 'LANG': 'en_US.UTF-8', 15:56:12 INFO - 'LANGUAGE': 'en_US:en', 15:56:12 INFO - 'LOGNAME': 'cltbld', 15:56:12 INFO - 'MAIL': '/var/mail/cltbld', 15:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:12 INFO - 'MOZ_NO_REMOTE': '1', 15:56:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:12 INFO - 'NO_EM_RESTART': '1', 15:56:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:12 INFO - 'PWD': '/builds/slave/test', 15:56:12 INFO - 'SHELL': '/bin/bash', 15:56:12 INFO - 'SHLVL': '1', 15:56:12 INFO - 'TERM': 'linux', 15:56:12 INFO - 'TMOUT': '86400', 15:56:12 INFO - 'USER': 'cltbld', 15:56:12 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:12 INFO - '_': '/tools/buildbot/bin/python'} 15:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:56:13 INFO - Cleaning up... 15:56:13 INFO - Return code: 0 15:56:13 INFO - Running post-action listener: _resource_record_post_action 15:56:13 INFO - Running post-action listener: _start_resource_monitoring 15:56:13 INFO - Starting resource monitoring. 15:56:13 INFO - [mozharness: 2017-09-05 22:56:13.205405Z] Finished create-virtualenv step (success) 15:56:13 INFO - [mozharness: 2017-09-05 22:56:13.205878Z] Running install step. 15:56:13 INFO - Running pre-action listener: _resource_record_pre_action 15:56:13 INFO - Running main action method: install 15:56:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:56:13 INFO - Reading from file tmpfile_stdout 15:56:13 INFO - Detecting whether we're running mozinstall >=1.0... 15:56:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:56:13 INFO - Reading from file tmpfile_stdout 15:56:13 INFO - Output received: 15:56:13 INFO - Usage: mozinstall [options] installer 15:56:13 INFO - Options: 15:56:13 INFO - -h, --help show this help message and exit 15:56:13 INFO - -d DEST, --destination=DEST 15:56:13 INFO - Directory to install application into. [default: 15:56:13 INFO - "/builds/slave/test"] 15:56:13 INFO - --app=APP Application being installed. [default: firefox] 15:56:13 INFO - mkdir: /builds/slave/test/build/application 15:56:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:56:30 INFO - Reading from file tmpfile_stdout 15:56:30 INFO - Output received: 15:56:30 INFO - /builds/slave/test/build/application/firefox/firefox 15:56:30 INFO - Running post-action listener: _resource_record_post_action 15:56:30 INFO - [mozharness: 2017-09-05 22:56:30.160246Z] Finished install step (success) 15:56:30 INFO - [mozharness: 2017-09-05 22:56:30.160501Z] Running setup-mitmproxy step. 15:56:30 INFO - Running pre-action listener: _resource_record_pre_action 15:56:30 INFO - Running main action method: setup_mitmproxy 15:56:30 INFO - Skipping: mitmproxy is not required 15:56:30 INFO - Running post-action listener: _resource_record_post_action 15:56:30 INFO - [mozharness: 2017-09-05 22:56:30.161688Z] Finished setup-mitmproxy step (success) 15:56:30 INFO - [mozharness: 2017-09-05 22:56:30.161910Z] Running run-tests step. 15:56:30 INFO - Running pre-action listener: _resource_record_pre_action 15:56:30 INFO - Running pre-action listener: _set_gcov_prefix 15:56:30 INFO - Running main action method: run_tests 15:56:30 WARNING - Try message not found. 15:56:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:56:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:56:30 INFO - Python 2.7.3 15:56:30 INFO - Return code: 0 15:56:30 INFO - grabbing minidump binary from tooltool 15:56:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:30 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 0x2cdc090>, '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 0x2cdcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cd6f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:56:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:56:30 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:56:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:56:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:56:30 INFO - Return code: 0 15:56:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:56:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:56:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:56:30 INFO - ENV: RUST_BACKTRACE is now full 15:56:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:56:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:56:30 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/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:56:30 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/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:56:30 INFO - Using env: {'DISPLAY': ':0', 15:56:30 INFO - 'HOME': '/home/cltbld', 15:56:30 INFO - 'LANG': 'en_US.UTF-8', 15:56:30 INFO - 'LANGUAGE': 'en_US:en', 15:56:30 INFO - 'LOGNAME': 'cltbld', 15:56:30 INFO - 'MAIL': '/var/mail/cltbld', 15:56:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:30 INFO - 'MOZ_NO_REMOTE': '1', 15:56:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:30 INFO - 'NO_EM_RESTART': '1', 15:56:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:30 INFO - 'PWD': '/builds/slave/test', 15:56:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:30 INFO - 'RUST_BACKTRACE': 'full', 15:56:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:56:30 INFO - 'SHELL': '/bin/bash', 15:56:30 INFO - 'SHLVL': '1', 15:56:30 INFO - 'STYLO_FORCE_ENABLED': '1', 15:56:30 INFO - 'STYLO_THREADS': '1', 15:56:30 INFO - 'TERM': 'linux', 15:56:30 INFO - 'TMOUT': '86400', 15:56:30 INFO - 'USER': 'cltbld', 15:56:30 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:30 INFO - '_': '/tools/buildbot/bin/python'} 15:56:30 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/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:56:30 INFO - mozversion application_buildid: 20170905215433 15:56:30 INFO - mozversion application_changeset: 978d2539a8d1a49e9f9705204f3918772b337547 15:56:30 INFO - mozversion application_display_name: Nightly 15:56:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:56:30 INFO - mozversion application_name: Firefox 15:56:30 INFO - mozversion application_remotingname: firefox 15:56:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:56:30 INFO - mozversion application_vendor: Mozilla 15:56:30 INFO - mozversion application_version: 57.0a1 15:56:30 INFO - mozversion platform_buildid: 20170905215433 15:56:30 INFO - mozversion platform_changeset: 978d2539a8d1a49e9f9705204f3918772b337547 15:56:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:56:30 INFO - mozversion platform_version: 57.0a1 15:56:30 INFO - using testdate: 1504652190 15:56:30 INFO - actual date: 1504652190 15:56:30 INFO - starting webserver on 'localhost:57022' 15:56:30 INFO - SUITE-START | Running 2 tests 15:56:30 INFO - TEST-START | dromaeo_css 15:56:30 INFO - Initialising browser for dromaeo_css test... 15:56:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57022/getInfo.html -profile /tmp/tmpssDNjP/profile 15:56:30 INFO - TEST-INFO | started process 14422 (/builds/slave/test/build/application/firefox/firefox http://localhost:57022/getInfo.html) 15:56:36 INFO - TEST-INFO | 14422: exit 0 15:56:36 INFO - Browser initialized. 15:56:36 INFO - Running cycle 1/1 for dromaeo_css test... 15:56:36 INFO - Using env: {'DISPLAY': ':0', 15:56:36 INFO - 'HOME': '/home/cltbld', 15:56:36 INFO - 'JSGC_DISABLE_POISONING': '1', 15:56:36 INFO - 'LANG': 'en_US.UTF-8', 15:56:36 INFO - 'LANGUAGE': 'en_US:en', 15:56:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:56:36 INFO - 'LOGNAME': 'cltbld', 15:56:36 INFO - 'MAIL': '/var/mail/cltbld', 15:56:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:56:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:56:36 INFO - 'MOZ_CRASHREPORTER': '1', 15:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:56:36 INFO - 'MOZ_NO_REMOTE': '1', 15:56:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:56:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:36 INFO - 'NO_EM_RESTART': '1', 15:56:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:36 INFO - 'PWD': '/builds/slave/test', 15:56:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:56:36 INFO - 'RUST_BACKTRACE': 'full', 15:56:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:56:36 INFO - 'SHELL': '/bin/bash', 15:56:36 INFO - 'SHLVL': '1', 15:56:36 INFO - 'STYLO_FORCE_ENABLED': '1', 15:56:36 INFO - 'STYLO_THREADS': '1', 15:56:36 INFO - 'TERM': 'linux', 15:56:36 INFO - 'TMOUT': '86400', 15:56:36 INFO - 'USER': 'cltbld', 15:56:36 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 15:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:36 INFO - '_': '/tools/buildbot/bin/python'} 15:56:36 INFO - TEST-INFO | started process 14603 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpssDNjP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:56:37 INFO - PID 14603 | 15:56:37 INFO - PID 14603 | (/builds/slave/test/build/application/firefox/firefox:14655): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:56:37 INFO - PID 14603 | 15:56:47 INFO - PID 14603 | RSS: Main: 172232704 15:56:47 INFO - PID 14603 | 15:59:44 INFO - PID 14603 | Cycle 1(1): loaded http://localhost:57022/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57022/tests/dromaeo/cssquery-ext.html) 15:59:46 INFO - PID 14603 | RSS: Main: 175652864 15:59:46 INFO - PID 14603 | 16:02:47 INFO - PID 14603 | Cycle 1(1): loaded http://localhost:57022/tests/dromaeo/cssquery-ext.html (next: http://localhost:57022/tests/dromaeo/cssquery-jquery.html) 16:02:48 INFO - PID 14603 | RSS: Main: 172027904 16:02:48 INFO - PID 14603 | 16:05:50 INFO - PID 14603 | Cycle 1(1): loaded http://localhost:57022/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57022/tests/dromaeo/cssquery-mootools.html) 16:05:50 INFO - PID 14603 | RSS: Main: 174215168 16:05:50 INFO - PID 14603 | 16:08:52 INFO - PID 14603 | Cycle 1(1): loaded http://localhost:57022/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57022/tests/dromaeo/cssquery-prototype.html) 16:08:53 INFO - PID 14603 | RSS: Main: 172847104 16:08:53 INFO - PID 14603 | 16:11:56 INFO - PID 14603 | Cycle 1(1): loaded http://localhost:57022/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57022/tests/dromaeo/cssquery-yui.html) 16:11:56 INFO - PID 14603 | RSS: Main: 173916160 16:11:56 INFO - PID 14603 | 16:14:58 INFO - PID 14603 | Cycle 1(1): loaded http://localhost:57022/tests/dromaeo/cssquery-yui.html (next: http://localhost:57022/tests/dromaeo/cssquery-dojo.html) 16:14:58 INFO - PID 14603 | RSS: Main: 176447488 16:14:58 INFO - PID 14603 | 16:14:58 INFO - PID 14603 | __start_tp_report 16:14:58 INFO - PID 14603 | _x_x_mozilla_page_load 16:14:58 INFO - PID 14603 | _x_x_mozilla_page_load_details 16:14:58 INFO - PID 14603 | |i|pagename|runs| 16:14:58 INFO - PID 14603 | |0;dojo.html;3300.2244472548946;3316.088075779161;3321.3357328534294;3345.2138747394356;3355.6476569960137;6404.263509696386;6408.166938298026;6438.645874476902;6486.416222539982;6498.057781621668;6234.314225435199;6347.873042539147;6358.61848289103;6364.713587888545;6366;5778;5782.595218334718;5787.71061446927;5807.419258074201;5826.6212696174825;5800.854978625526;5826.300843898717;5925.614835035709;5936.64380137191;5949.048152295633;5933.970330148343;5935.584509084344;5982.521398288127;6003.369646187153;6071.666058366788;5636.661800291996;5785.161151633031;5868.853278668024;5909.940900590982;6005.57960942732;5789.8263052108505;5803.390643982363;5810.33181184165;5818;5891.175235467038;6284.685765711696;6385.904211436832;6451.612903225821;6468.159139311906;6523.739050437976;6659.533832631718;6801.319868013208;6928.549644273106;6957.408620267283;7049.189343225544;6970.059042029312;7057.435405167575;7058.647067646597;7063.646817659096;7090.71637134514;6439.130717353191;6519.445847103002;6521.184851893513;6522.967385163091;6538.346165383424;7051.612161331176;7124.821879452951;7152.105986751656;7225.674844632004;7232.710691572278;6620.602763834186;6710.127683401127;6773;6846.041554182419;6905.551139175937;6894.207166175854;6919.065926099912;6923.515353925278;7047.43620510358;7051.682674279669;6906.896396554056;6925.134358205224;6978.965105174543;6993.930060699327;7012.894806577905;6943.72225111;6950.270221626757;6961.686724097428;7064.858702826017;7065.222825489295;5303.204519322056;5425.593080518976;5448.291722076105;5488.643238189505;5556.6943818089585;4751.762411879392;4769;4813.807447702053;4825.469198388175;4826.203676393426;5750.6837123958585;5840.386759390288;5902.61632993854;5935.020721580977;5993.580449368503;6879;6894.896576551256;6895.413889819321;6896.7241060403985;6898.655067246617;5909.556783241274;5934;5937.495312898365;5938.554608404387;5946.672932988728;5937.6734279614175;6029.276486821609;6034.094885767082;6047.697615119227;6049.21360223177;6689.866202675919;6725.024871393621;6730.0577919091365;6785.287544807823;6803.897941530881;6788.59268443895;6850.349216824394;6984.371406573433;7045.0489183960535;7068;6648.767293144911;6689.899651505134;6703.4972377070935;6735.797926062226;6737.797866064026;6793.626350550767;6829.112215411965;6860.0738900247825;6959.130108736408;6963.025176475398;6975.162980442379;6998.790036298919;7067.328603782633;7092.25531319204;7097.325754053357;6477.157969464128;6595.274519802721;6647.700853461509;6713.832154196184;6831.077804496528;6699.229588597374;6701.631410272287;6709.832254193684;6727.461803055841;6758.831029224309;6616.66916654175;6623.039659249285;6694.564853284521;6697;6710.261871194262;6661.900071499028;6777.864442711217;6848.075509806312;6850.520463567503;6860.794176174722;6340.65126418033;6489.9351006491215;6522.478201743754;6631.403173714389;6633.6019838809825 16:14:58 INFO - PID 14603 | |1;ext.html;733.2703959560239;787.193127044779;811.4319976016787;819.8934138561985;1129.2829053550993;37106.62893371066;40769.3884591731;40862.59137408625;41048.38427423591;41071.794641026754;40779.18441631173;41182.588174118326;41333.17333653333;41541.37687934683;41609;7528.999999999986;7600.239976002411;7723.343515801135;7790.298873101419;7798.64906079225;7683.807904802369;7795.610219489031;7798.337141342992;7800;7800.687972481094;7725.806854828618;7757.030371203599;7786;7797.883031754528;7800.882986755175;7717.845643087163;7747.341475974521;7752;7758.844823103563;7760.301572858441;7675.846483070363;7676.155622881479;7712.460127791001;7713.49862258957;7723.266289702469;36498.8175059123;36899.4465083024;36981.44527832085;36989.815050924575;37024.629753702386;3479.860805567774;3488.947665785015;3518.66572675598;3520.9471857922154;3524.577050753926;30037.249068773235;30091.548626770615;30235.395292094036;30258.69741302581;30261.394772104435;4898.265260210961;5077.136886729265;5084.13569693153;5112.233165025239;5113.616478764107;178231;178566;180195;180407;180826.19173808224;45550;45556.77221613937;45696.3145552817;45779.31331030037;45835.31247031297;48003.75998120057;48181.5181848177;48212.27681584779;48263.27605085926;48268.517314827106;11975.341356225492;12214.206076604993;14153.150810951376;14499.130052196902;14500.927495362666;10241.7709874814;10457.215708821717;10473.685789426178;10475.580976760997;10505.947470262752;13638.795418068737;13755.17468951866;13789.379477923518;13789.931050344683;13910.165390076425;5551.722413879371;5623.522000629992;5634.352049514277;5680.233168522279;5723.713814309268;990.4700984973005;992.8262554053011;993.8062077894886;1016.9644062457778;1017.7862648843677;22073.558528829333;22599.887000564893;22717;22791.658125128135;23024.769752302596;1085.571199376242;1093.1200383691205;1096.6107032003683;1099.0603034405628;1108.4291589831244;11822.940885295518;11900.404979750978;12095.81856272139;12169.634910952685;12176;222485;224459;225163;225261;225992;48688.26967595489;49053.75473122683;49071.509284907406;49249.26126108111;49387.50612493829;1391.6729568551382;1412.773956167029;1435.6052085676522;1436.906601070927;1439.9208043557496;22441.887790560944;22521.211757588404;22580.661290080334;22655.88672056629;22833.543329132994;36213;36292.81853590715;36294.455583166804;36300;36375;187221;187595;187710;187850;187853;32983.17542061468;33134.66865331316;35136.82431587826;35878;35910.28179436344;45533.31700024436;46148.76925615351;46392;46447;46562.767186165205;33070.17324566905;33590.83204583961;33745.83127084349;34279.48580771241;34396.65603343934;33404.167433897615;33415.33169336648;33571.66428335783;33597.32805343831;34016;37766.62233377631;37965.24069518649;38099.42850857184;38111.80944095262;38216.235675286895;32515.34969300648;32570.83714581412;32818;33641.3271734559;33818;24201.515969680157;24628.50742985166;24990.375240618403;25390.746092538837;25456.490870182864 16:14:58 INFO - PID 14603 | |2;jquery.html;562.8170844475544;563.636454486856;572.3418069220397;574.6867956963455;575.9251297331347;21739.23912663057;22616.77383226167;22641.433964150863;22649.54700905981;22686.88656556717;23579.88210058947;25937.22188334348;25975.480490390182;26043.86978065112;26081.34796630085;6638.70125844337;6784.864302713942;6786.660666966656;6789.558678685894;6805.55763875349;6526;6718.563293385914;6781.355771201728;7092.255313192118;7102;7100.183478899941;7114.715411383564;7117.466190035742;7117.644117794115;7117.85764284714;7125.572465652078;7128.215896251409;7159.642017899084;7159.961805538193;7164.247753985861;6992.510524263253;6996.965015174892;6999.650017499104;7008.369246767765;7025.121859767529;30138.397232055235;30565.541516877263;30679.38641227185;30824.53763193554;30865.074047778602;2093.665013597832;2120.4698825293676;2121.193946300396;2122;2127.0853532980814;20386.49033774153;20978.5804283915;21001.5799684007;21032;21058.894705526527;2390.9001859144814;2436.074291769134;2512.623106534016;2553.6807899012624;2559.912037384123;131919.34040329736;132739;135732;136497.3175134128;136676;19315.04041671454;20300.28948986808;20601.48496287605;21411.8929405352;22177.778222217894;40138.197236055115;40182.1963560733;40204;40225.798871005456;40308.79845600812;9307.441553506676;9489.525523723787;9495.857562136573;9595.616175353047;9690.95154524223;7554.584497852671;7655.846883062308;7672.079350477867;7692.730754423568;7762.883556746653;8936.078743070271;10335.793284134426;10792.028717415313;11255.606053788078;11301.547938082549;3639.399499082622;3778.886633401002;3987.3620220764537;4122.898504438733;4181.456410666655;928.8467402878584;948.9810203796023;973.9466771670862;986.8470387090013;993.7416271769248;19628.607427851362;19659.213631454866;19909.900450497655;20007.59984800296;20210.696839547418;989.5200827598609;1025.74869157057;1031.169908223872;1031.5300696507447;1044.0968562193648;10418.426986515815;10918.344899306067;10938.890611093946;11082.778344433065;11138.49876755548;455517;480194;489087;489617;495971;108625;113885.4305728466;113889.43055284671;115709;115830;1106.0985296982933;1122.7529943412437;1148.4028305281206;1150.6663067710438;1156.346664134778;17241.137943103047;17252.827471725624;17513.824861751218;17892.642147156723;17894.28422863071;51951.48048519466;54306.09928652478;54355.45644543504;55204.44795552154;55285.44714552803;63857.68071159614;64240.35759642531;64480;65545;71822.64088679523;12456.252624842538;12681.112322137362;14154.221517816619;14497.840172786362;15034.774478383051;23150.768492315536;23515.52968940646;23860.88069559641;24448.14431494889;24742.134025309027;18955.14701838393;19036.904815475835;19305.8069419304;19345.61308773845;19349.709754353415;16611.66776664484;16928.49214523566;17075.40236091731;17113.74329385085;17377.218025188646;19462.221511139396;19601.60796784085;19710.40868773939;19820.90089549543;19892.403227903185;14296.642583935069;14620.780688289471;14621.63445913818;14631.195284259467;14648.853511465175;18278.177482013074;18534.629307414045;18545.81454185495;18550.165242563846;18551 16:14:58 INFO - PID 14603 | |3;mootools.html;1271.4342117757599;1327.4889167670444;1346.9259190744506;1349.932503374831;1352.9661758456034;14732.705345893075;15076.773848392282;15452.381904723798;15460.922695386522;15494;15744.29150688219;15965.521034368958;15980;15996.040237585754;16009.359625614961;2628.776553992913;2691.24645099372;2746.2722378569697;2746.997345968727;2750.7661848742882;2705.2290097322266;2736.097087960978;2741.136541989277;2741.2461573067367;2742.437800250954;2712.050782226225;2724.632174656426;2725.1143378402;2739.643846299988;2744.2041807875694;2589.313831834556;2596.7273436289106;2603.648507451489;2610;2614.725453827349;2552.6681531400986;2554.859482728449;2575.9098431554808;2575.935601609956;2577.510273048115;14858;15054.623634409118;15085.24573771307;15179.16514591697;15182.696346073017;7676.270754278335;7976.7208147714555;7979.281864632153;7982.042154941444;7988.480748751313;5034.546890779848;5243.370795504564;5357.544608708265;5362.839114826562;5365.946340536584;1883.3031778242;1935.6128774245208;1953.6483432982056;1988.721578978944;2000.8399328053865;24988.625170622454;25823.35441613956;26063.218103456926;26065.478690426084;26558.6016209757;6577.177852768308;6691.933080669229;6786.117804685359;6857.451403887677;6908.930910690829;16572.591329737108;17015.23431445588;17058.402955896483;17098.572535686457;17494.65010699779;7565.659545320474;7831.960840195762;7842.45102842807;7846.803829904184;7850.2542258485355;7051.294870512907;7159.92840071603;7180.3537681608905;7215.711371545184;7230.710771569184;4973.776180071905;5096.311997880312;5113.974430127825;5125.103106956342;5171.81898633546;1450.5358285348798;1454.2292584930003;1461.3935216884875;1472.7569950957973;1473.1971075763734;879;910.39003867409;913.36064754672;914.8993610702818;921.2537844346101;7761.4566980311965;7772.339351155102;7799.454038217384;7817.218278172138;7837.725679601187;956.297121615612;1006.8691070160833;1009.7627057641448;1010.6109147978082;1013.8986101389802;5562.221289019541;5621.184928185472;5630.352509461382;5683.0338842396895;5715.942840571624;21506.247281345077;21793.23723669664;21867.343979680634;21940.670889936664;22201.555968880533;20898.37304880856;21153.89423052906;21320;21323.893380533307;21899.890500547397;11066.39134847591;11481.827772583418;11499.712507187387;11544.769104618028;11584.710382240342;8201.13888041747;8282.296004839656;8401.83196336082;8443.324534037385;8585.313174946114;18056.819431805514;18095.63808723792;18467.81532184661;18751.90624046871;18932.242710291986;18790.624187516445;19118.426447206606;19446.902765486084;19522.316718914768;19659.213631454582;10406.063454289151;10636.680899573024;10655;10655.520501577292;10659.573617055541;13964.2319672419;14085.647858803612;14163.221022843842;14226.075305105134;14281.071730337495;11169.776604467703;11248.94375528117;11362.261453005529;11423.828642570534;11454.08367330595;11065.225434219527;11132.777344453227;11252.099832013255;11272;11401.543938242377;10195.133413659922;10214.233932455245;10228.232882533692;10239.232057595562;10289.125424339016;7703.345215656508;7756.108047574601;7800.102988156208;7818.687252509836;7837.451378403457;7338.926610734038;7681.69273229087;7688.46180767341;7692.730754423568;7696.8460630788195 16:14:58 INFO - PID 14603 | |4;prototype.html;500.49558876927165;511.7364557253014;531.7367902888071;535.1597991153889;540.1789280293954;6346.904796428052;6378.585391949531;6397.385477205382;6401.807945761635;6417.35826417358;6209.161763161983;6233.503959049829;6271.905921411171;6312.715927783261;6375.808725738224;5185.377754669445;5219.9478005219935;5249.86875328116;5256.421793602701;5279.366476022863;4905.847125925405;5018.924716129242;5068.949310506903;5154.123798954187;5191.714455704935;4983.405310300705;4999.325091112708;5046.571041461461;5056.949430505685;5061.873453163664;4993.700377977333;4998.90002199954;4999.175136102539;5044.949550504503;5070.6957582545165;4426.18115648606;4506.707064040859;4530.365748795171;4532.750698711569;4551.249043907751;6128.540359473057;6143.017117261262;6186.7525298987985;6196.256449226076;6239.344868788802;5620.864071652787;5629.408912064257;5689.203511508392;5697.857553561194;5738.454846789548;6542.906269993604;6553.377429144223;6631.237407698129;6640.7343706251695;6660.9000864987065;4913.926291105637;4921.18800397934;4921.753912304406;4947.579455746266;4955.306257124005;6611.636360000198;6806.659667016679;6843.349881761224;6871.484638652121;6953.4354770176815;4971.328870602422;5010.574101201439;5016.849494515171;5066.746662666852;5078.162103252996;5105.927755171456;5112.821051263262;5153.7165455900285;5161.845144645667;5165.535101840853;5702.857428564319;5720.770034442531;5737.536928083396;5750.712464376765;5793.855153621109;6583.979483180115;6598.934010659928;6609.239937407164;6640.468762498988;6726.697298621574;1760.9082368931345;1768.9115544222996;1785.749995000701;1788.8837225580296;1813.610073834132;1143.3482914738686;1217.2088142707134;1225.822255772908;1235.734317121823;1243.5461056714234;5839.211706419579;5897.675627840425;5900.262467191601;5938.6139900907165;5948.018576934757;6666.899996500057;6736.360045795642;6862.348076932683;6890.1741038624805;6914.545336405504;5734.942650573441;5754.1944127822135;5790.971045144832;5791.797287095016;5907.320658124285;3630.1832087780567;3634.3639863023864;3653.1962968146963;3667.587978628194;3674.595794462607;6556.213254409501;6596.241432235259;6665.166854143232;6685.565438246498;6741.426978706767;6391.904121438182;6794.4904132189295;7042.647867606599;7061.858762824715;7104.396126329217;6412.396900774806;6427.646479443675;6462.741490340334;6539.5422320438065;6566.832945128001;6534.509911756542;6619.271880093283;6681.933180668131;6741.146374610273;6894.070379883618;6681.398674119353;6698.497612678971;6729.596224226562;6731.653669266264;6881.243063262937;6522.043386983981;6524.902126468011;6560.147180866456;6627.271000190071;6662.766803161866;4897.673605037721;5024.648274620741;5025.246213068069;5082.974585127198;5088.440271570198;6565.540412171103;6710.101625813086;6808.319168083152;6835.350641689031;6855.280195579392;6338.3729877190835;6539.901901471577;6573.408393244631;6593.274739778716;6691.126484584324;6458.967705161444;6508;6608.768693095911;6610;6653.800385988428;6481.675916204265;6535.215774107138;6560.40956313934;6585.012248162814;6627.867442651023;6720.260771315054;6737.393634572912;6750.45996320303;6779.728810847708;6783.287754785873;6445.935540644534;6446.8388290293115;6487.3188315226225;6502.219733631994;6531.248906375828 16:14:58 INFO - PID 14603 | |5;yui.html;898.7483504618709;907.369378282094;923.5290002098927;926.4950601921952;934.4953724988507;18574.25702971881;19586.706199406985;19640.116194771235;19654.508637284056;22369.888150559225;20931.895340523275;21355.466113347135;21417.14331426741;21444.785552144513;21456;982.5480279071618;1004.1264100232808;1007.8488226766007;1008.0927165551026;1008.1330056151704;954.7135859242235;971.2327261463436;971.9076687714655;976.677696360203;985.7042887133867;1044.8537204791335;1050.800347933894;1051.8842927277992;1052.147760314148;1054.8892366301543;1037.4708898461802;1044.7074819050601;1045.2578669144916;1045.2839804733744;1045.806525792727;763.1147868472545;765.2271206081841;768.2739810878721;773.8916551682769;774.0324594257179;19802.306919257902;19949.30177443797;20045.799542004686;20059.799402006083;20416.183352666023;20985.265515706877;21168.364949051553;21588.136474541;21595;21607;16983.3206671733;17259.13704314792;17345;17429.38997135094;17450.127493625267;3806.352920003606;3838.7504812187117;3839.9424008639894;3840.481534992768;3840.539135303781;129921;130104.349478252;130859.34570327183;134223;134413;16466;16818.327266909186;17049.829501705073;17324.566885827702;17552.64894702099;30606.846965765028;31319;31694.524582131286;31988;32197.839010805266;7193.89209161863;7480.364169045583;8856.690015849414;9213.861792073125;9423.34036617444;4244.830206791694;4352.173087113439;4357.607815296638;4361.760103194355;4363.847265345697;5174.327337446108;5269.1832765921345;5304.38999515053;5320.733963301819;5335.653182543122;1036.4713995862164;1057.582255009267;1063.9095676867553;1074.5809134437575;1074.6185104288022;662.5991275278484;663.3234101216829;679.1035832700886;689.8723736108836;690.378659206718;11708.356040417688;12668.239905605693;13614.387352569158;13766.518171864138;14268.357923893449;682.4847240333517;706.2364352442704;707.4481904114799;717.7129148340601;721.6752461392394;5524.447555244444;5833.854153646193;5955.583109182402;6062.878367502026;6076.513878889678;104149;107558.46220769003;109110.45444772726;113071.47199824564;114051.42974285076;95959.52020239994;96421.51789241009;97900;99093.50453247687;100358;79682;80576.19423805804;80860;81345.18654813494;82136;13100.082994190316;13285.13646612967;13297.468101275841;13334.26661533625;13362.532311369056;39058.80470597629;39963.20073598454;40380.59619403886;40437.79781101076;40774.79612601918;50699;51137.178363738225;51151.48848511467;51516;52949.735251323495;17809.287628494716;18283.360082397052;18315.908420457814;18328.083595820422;18336;16745.665086698442;20716.171353145706;21056.68414973746;21129.788702112783;21193.78806211918;17187.31250749956;17416.91291543585;17547.385841495485;17599.384021559184;17604.647907042043;17362.13189340553;17463;17524.47426577205;17616.91191544034;17633.73549396786;17778.199981000627;18832.62334753325;18895.716564251274;19080.8091919079;19191.616167676846;11953.760924781629;12645.494180233056;12789.93605031969;13000.804987925;13009.349532523525;13826.723465530835;13855.515056972958;13898.51355202563;13918.930405347908;14005.579832605039 16:14:58 INFO - PID 14603 | __end_tp_report 16:14:58 INFO - PID 14603 | __start_cc_report 16:14:58 INFO - PID 14603 | _x_x_mozilla_cycle_collect,359 16:14:58 INFO - PID 14603 | __end_cc_report 16:14:58 INFO - PID 14603 | __startTimestamp1504653298952__endTimestamp 16:14:58 INFO - PID 14603 | 16:14:58 INFO - PID 14603 | ------- Summary: start ------- 16:14:58 INFO - PID 14603 | Number of tests: 6 16:14:58 INFO - PID 14603 | 16:14:58 INFO - PID 14603 | [#0] dojo.html Cycles:165 Average:6358.11 Median:6623.04 stddev:766.68 (11.6%) stddev-sans-first:730.54 16:14:58 INFO - PID 14603 | Values: 3300.2 3316.1 3321.3 3345.2 3355.6 6404.3 6408.2 6438.6 6486.4 6498.1 6234.3 6347.9 6358.6 6364.7 6366.0 5778.0 5782.6 5787.7 5807.4 5826.6 5800.9 5826.3 5925.6 5936.6 5949.0 5934.0 5935.6 5982.5 6003.4 6071.7 5636.7 5785.2 5868.9 5909.9 6005.6 5789.8 5803.4 5810.3 5818.0 5891.2 6284.7 6385.9 6451.6 6468.2 6523.7 6659.5 6801.3 6928.5 6957.4 7049.2 6970.1 7057.4 7058.6 7063.6 7090.7 6439.1 6519.4 6521.2 6523.0 6538.3 7051.6 7124.8 7152.1 7225.7 7232.7 6620.6 6710.1 6773.0 6846.0 6905.6 6894.2 6919.1 6923.5 7047.4 7051.7 6906.9 6925.1 6979.0 6993.9 7012.9 6943.7 6950.3 6961.7 7064.9 7065.2 5303.2 5425.6 5448.3 5488.6 5556.7 4751.8 4769.0 4813.8 4825.5 4826.2 5750.7 5840.4 5902.6 5935.0 5993.6 6879.0 6894.9 6895.4 6896.7 6898.7 5909.6 5934.0 5937.5 5938.6 5946.7 5937.7 6029.3 6034.1 6047.7 6049.2 6689.9 6725.0 6730.1 6785.3 6803.9 6788.6 6850.3 6984.4 7045.0 7068.0 6648.8 6689.9 6703.5 6735.8 6737.8 6793.6 6829.1 6860.1 6959.1 6963.0 6975.2 6998.8 7067.3 7092.3 7097.3 6477.2 6595.3 6647.7 6713.8 6831.1 6699.2 6701.6 6709.8 6727.5 6758.8 6616.7 6623.0 6694.6 6697.0 6710.3 6661.9 6777.9 6848.1 6850.5 6860.8 6340.7 6489.9 6522.5 6631.4 6633.6 16:14:58 INFO - PID 14603 | 16:14:58 INFO - PID 14603 | [#1] ext.html Cycles:180 Average:36562.27 Median:19701.29 stddev:51339.94 (260.6%) stddev-sans-first:51413.47 16:14:58 INFO - PID 14603 | Values: 733.3 787.2 811.4 819.9 1129.3 37106.6 40769.4 40862.6 41048.4 41071.8 40779.2 41182.6 41333.2 41541.4 41609.0 7529.0 7600.2 7723.3 7790.3 7798.6 7683.8 7795.6 7798.3 7800.0 7800.7 7725.8 7757.0 7786.0 7797.9 7800.9 7717.8 7747.3 7752.0 7758.8 7760.3 7675.8 7676.2 7712.5 7713.5 7723.3 36498.8 36899.4 36981.4 36989.8 37024.6 3479.9 3488.9 3518.7 3520.9 3524.6 30037.2 30091.5 30235.4 30258.7 30261.4 4898.3 5077.1 5084.1 5112.2 5113.6 178231.0 178566.0 180195.0 180407.0 180826.2 45550.0 45556.8 45696.3 45779.3 45835.3 48003.8 48181.5 48212.3 48263.3 48268.5 11975.3 12214.2 14153.2 14499.1 14500.9 10241.8 10457.2 10473.7 10475.6 10505.9 13638.8 13755.2 13789.4 13789.9 13910.2 5551.7 5623.5 5634.4 5680.2 5723.7 990.5 992.8 993.8 1017.0 1017.8 22073.6 22599.9 22717.0 22791.7 23024.8 1085.6 1093.1 1096.6 1099.1 1108.4 11822.9 11900.4 12095.8 12169.6 12176.0 222485.0 224459.0 225163.0 225261.0 225992.0 48688.3 49053.8 49071.5 49249.3 49387.5 1391.7 1412.8 1435.6 1436.9 1439.9 22441.9 22521.2 22580.7 22655.9 22833.5 36213.0 36292.8 36294.5 36300.0 36375.0 187221.0 187595.0 187710.0 187850.0 187853.0 32983.2 33134.7 35136.8 35878.0 35910.3 45533.3 46148.8 46392.0 46447.0 46562.8 33070.2 33590.8 33745.8 34279.5 34396.7 33404.2 33415.3 33571.7 33597.3 34016.0 37766.6 37965.2 38099.4 38111.8 38216.2 32515.3 32570.8 32818.0 33641.3 33818.0 24201.5 24628.5 24990.4 25390.7 25456.5 16:14:58 INFO - PID 14603 | 16:14:58 INFO - PID 14603 | [#2] jquery.html Cycles:180 Average:35025.16 Median:23720.38 stddev:81037.85 (341.6%) stddev-sans-first:81223.87 16:14:58 INFO - PID 14603 | Values: 562.8 563.6 572.3 574.7 575.9 21739.2 22616.8 22641.4 22649.5 22686.9 23579.9 25937.2 25975.5 26043.9 26081.3 6638.7 6784.9 6786.7 6789.6 6805.6 6526.0 6718.6 6781.4 7092.3 7102.0 7100.2 7114.7 7117.5 7117.6 7117.9 7125.6 7128.2 7159.6 7160.0 7164.2 6992.5 6997.0 6999.7 7008.4 7025.1 30138.4 30565.5 30679.4 30824.5 30865.1 2093.7 2120.5 2121.2 2122.0 2127.1 20386.5 20978.6 21001.6 21032.0 21058.9 2390.9 2436.1 2512.6 2553.7 2559.9 131919.3 132739.0 135732.0 136497.3 136676.0 19315.0 20300.3 20601.5 21411.9 22177.8 40138.2 40182.2 40204.0 40225.8 40308.8 9307.4 9489.5 9495.9 9595.6 9691.0 7554.6 7655.8 7672.1 7692.7 7762.9 8936.1 10335.8 10792.0 11255.6 11301.5 3639.4 3778.9 3987.4 4122.9 4181.5 928.8 949.0 973.9 986.8 993.7 19628.6 19659.2 19909.9 20007.6 20210.7 989.5 1025.7 1031.2 1031.5 1044.1 10418.4 10918.3 10938.9 11082.8 11138.5 455517.0 480194.0 489087.0 489617.0 495971.0 108625.0 113885.4 113889.4 115709.0 115830.0 1106.1 1122.8 1148.4 1150.7 1156.3 17241.1 17252.8 17513.8 17892.6 17894.3 51951.5 54306.1 54355.5 55204.4 55285.4 63857.7 64240.4 64480.0 65545.0 71822.6 12456.3 12681.1 14154.2 14497.8 15034.8 23150.8 23515.5 23860.9 24448.1 24742.1 18955.1 19036.9 19305.8 19345.6 19349.7 16611.7 16928.5 17075.4 17113.7 17377.2 19462.2 19601.6 19710.4 19820.9 19892.4 14296.6 14620.8 14621.6 14631.2 14648.9 18278.2 18534.6 18545.8 18550.2 18551.0 16:14:58 INFO - PID 14603 | 16:14:58 INFO - PID 14603 | [#3] mootools.html Cycles:180 Average:9343.20 Median:2553.76 stddev:6649.28 (260.4%) stddev-sans-first:6640.28 16:14:58 INFO - PID 14603 | Values: 1271.4 1327.5 1346.9 1349.9 1353.0 14732.7 15076.8 15452.4 15460.9 15494.0 15744.3 15965.5 15980.0 15996.0 16009.4 2628.8 2691.2 2746.3 2747.0 2750.8 2705.2 2736.1 2741.1 2741.2 2742.4 2712.1 2724.6 2725.1 2739.6 2744.2 2589.3 2596.7 2603.6 2610.0 2614.7 2552.7 2554.9 2575.9 2575.9 2577.5 14858.0 15054.6 15085.2 15179.2 15182.7 7676.3 7976.7 7979.3 7982.0 7988.5 5034.5 5243.4 5357.5 5362.8 5365.9 1883.3 1935.6 1953.6 1988.7 2000.8 24988.6 25823.4 26063.2 26065.5 26558.6 6577.2 6691.9 6786.1 6857.5 6908.9 16572.6 17015.2 17058.4 17098.6 17494.7 7565.7 7832.0 7842.5 7846.8 7850.3 7051.3 7159.9 7180.4 7215.7 7230.7 4973.8 5096.3 5114.0 5125.1 5171.8 1450.5 1454.2 1461.4 1472.8 1473.2 879.0 910.4 913.4 914.9 921.3 7761.5 7772.3 7799.5 7817.2 7837.7 956.3 1006.9 1009.8 1010.6 1013.9 5562.2 5621.2 5630.4 5683.0 5715.9 21506.2 21793.2 21867.3 21940.7 22201.6 20898.4 21153.9 21320.0 21323.9 21899.9 11066.4 11481.8 11499.7 11544.8 11584.7 8201.1 8282.3 8401.8 8443.3 8585.3 18056.8 18095.6 18467.8 18751.9 18932.2 18790.6 19118.4 19446.9 19522.3 19659.2 10406.1 10636.7 10655.0 10655.5 10659.6 13964.2 14085.6 14163.2 14226.1 14281.1 11169.8 11248.9 11362.3 11423.8 11454.1 11065.2 11132.8 11252.1 11272.0 11401.5 10195.1 10214.2 10228.2 10239.2 10289.1 7703.3 7756.1 7800.1 7818.7 7837.5 7338.9 7681.7 7688.5 7692.7 7696.8 16:14:58 INFO - PID 14603 | 16:14:58 INFO - PID 14603 | [#4] prototype.html Cycles:180 Average:5574.73 Median:6236.42 stddev:1558.14 (25.0%) stddev-sans-first:1515.25 16:14:58 INFO - PID 14603 | Values: 500.5 511.7 531.7 535.2 540.2 6346.9 6378.6 6397.4 6401.8 6417.4 6209.2 6233.5 6271.9 6312.7 6375.8 5185.4 5219.9 5249.9 5256.4 5279.4 4905.8 5018.9 5068.9 5154.1 5191.7 4983.4 4999.3 5046.6 5056.9 5061.9 4993.7 4998.9 4999.2 5044.9 5070.7 4426.2 4506.7 4530.4 4532.8 4551.2 6128.5 6143.0 6186.8 6196.3 6239.3 5620.9 5629.4 5689.2 5697.9 5738.5 6542.9 6553.4 6631.2 6640.7 6660.9 4913.9 4921.2 4921.8 4947.6 4955.3 6611.6 6806.7 6843.3 6871.5 6953.4 4971.3 5010.6 5016.8 5066.7 5078.2 5105.9 5112.8 5153.7 5161.8 5165.5 5702.9 5720.8 5737.5 5750.7 5793.9 6584.0 6598.9 6609.2 6640.5 6726.7 1760.9 1768.9 1785.7 1788.9 1813.6 1143.3 1217.2 1225.8 1235.7 1243.5 5839.2 5897.7 5900.3 5938.6 5948.0 6666.9 6736.4 6862.3 6890.2 6914.5 5734.9 5754.2 5791.0 5791.8 5907.3 3630.2 3634.4 3653.2 3667.6 3674.6 6556.2 6596.2 6665.2 6685.6 6741.4 6391.9 6794.5 7042.6 7061.9 7104.4 6412.4 6427.6 6462.7 6539.5 6566.8 6534.5 6619.3 6681.9 6741.1 6894.1 6681.4 6698.5 6729.6 6731.7 6881.2 6522.0 6524.9 6560.1 6627.3 6662.8 4897.7 5024.6 5025.2 5083.0 5088.4 6565.5 6710.1 6808.3 6835.4 6855.3 6338.4 6539.9 6573.4 6593.3 6691.1 6459.0 6508.0 6608.8 6610.0 6653.8 6481.7 6535.2 6560.4 6585.0 6627.9 6720.3 6737.4 6750.5 6779.7 6783.3 6445.9 6446.8 6487.3 6502.2 6531.2 16:14:58 INFO - PID 14603 | 16:14:58 INFO - PID 14603 | [#5] yui.html Cycles:180 Average:23382.41 Median:20824.03 stddev:31727.64 (152.4%) stddev-sans-first:31771.72 16:14:58 INFO - PID 14603 | Values: 898.7 907.4 923.5 926.5 934.5 18574.3 19586.7 19640.1 19654.5 22369.9 20931.9 21355.5 21417.1 21444.8 21456.0 982.5 1004.1 1007.8 1008.1 1008.1 954.7 971.2 971.9 976.7 985.7 1044.9 1050.8 1051.9 1052.1 1054.9 1037.5 1044.7 1045.3 1045.3 1045.8 763.1 765.2 768.3 773.9 774.0 19802.3 19949.3 20045.8 20059.8 20416.2 20985.3 21168.4 21588.1 21595.0 21607.0 16983.3 17259.1 17345.0 17429.4 17450.1 3806.4 3838.8 3839.9 3840.5 3840.5 129921.0 130104.3 130859.3 134223.0 134413.0 16466.0 16818.3 17049.8 17324.6 17552.6 30606.8 31319.0 31694.5 31988.0 32197.8 7193.9 7480.4 8856.7 9213.9 9423.3 4244.8 4352.2 4357.6 4361.8 4363.8 5174.3 5269.2 5304.4 5320.7 5335.7 1036.5 1057.6 1063.9 1074.6 1074.6 662.6 663.3 679.1 689.9 690.4 11708.4 12668.2 13614.4 13766.5 14268.4 682.5 706.2 707.4 717.7 721.7 5524.4 5833.9 5955.6 6062.9 6076.5 104149.0 107558.5 109110.5 113071.5 114051.4 95959.5 96421.5 97900.0 99093.5 100358.0 79682.0 80576.2 80860.0 81345.2 82136.0 13100.1 13285.1 13297.5 13334.3 13362.5 39058.8 39963.2 40380.6 40437.8 40774.8 50699.0 51137.2 51151.5 51516.0 52949.7 17809.3 18283.4 18315.9 18328.1 18336.0 16745.7 20716.2 21056.7 21129.8 21193.8 17187.3 17416.9 17547.4 17599.4 17604.6 17362.1 17463.0 17524.5 17616.9 17633.7 17778.2 18832.6 18895.7 19080.8 19191.6 11953.8 12645.5 12789.9 13000.8 13009.3 13826.7 13855.5 13898.5 13918.9 14005.6 16:14:58 INFO - PID 14603 | -------- Summary: end -------- 16:14:58 INFO - PID 14603 | 16:14:59 INFO - TEST-INFO | 14603: exit 0 16:14:59 INFO - TEST-OK | dromaeo_css | took 1109151ms 16:14:59 INFO - TEST-START | kraken 16:14:59 INFO - Initialising browser for kraken test... 16:14:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57022/getInfo.html -profile /tmp/tmpzxGe6e/profile 16:14:59 INFO - TEST-INFO | started process 14974 (/builds/slave/test/build/application/firefox/firefox http://localhost:57022/getInfo.html) 16:15:05 INFO - TEST-INFO | 14974: exit 0 16:15:05 INFO - Browser initialized. 16:15:05 INFO - Running cycle 1/1 for kraken test... 16:15:05 INFO - Using env: {'DISPLAY': ':0', 16:15:05 INFO - 'HOME': '/home/cltbld', 16:15:05 INFO - 'JSGC_DISABLE_POISONING': '1', 16:15:05 INFO - 'LANG': 'en_US.UTF-8', 16:15:05 INFO - 'LANGUAGE': 'en_US:en', 16:15:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:15:05 INFO - 'LOGNAME': 'cltbld', 16:15:05 INFO - 'MAIL': '/var/mail/cltbld', 16:15:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:15:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:15:05 INFO - 'MOZ_CRASHREPORTER': '1', 16:15:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:15:05 INFO - 'MOZ_NO_REMOTE': '1', 16:15:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:15:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:05 INFO - 'NO_EM_RESTART': '1', 16:15:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:05 INFO - 'PWD': '/builds/slave/test', 16:15:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:15:05 INFO - 'RUST_BACKTRACE': 'full', 16:15:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:15:05 INFO - 'SHELL': '/bin/bash', 16:15:05 INFO - 'SHLVL': '1', 16:15:05 INFO - 'STYLO_FORCE_ENABLED': '1', 16:15:05 INFO - 'STYLO_THREADS': '1', 16:15:05 INFO - 'TERM': 'linux', 16:15:05 INFO - 'TMOUT': '86400', 16:15:05 INFO - 'USER': 'cltbld', 16:15:05 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301', 16:15:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:05 INFO - '_': '/tools/buildbot/bin/python'} 16:15:05 INFO - TEST-INFO | started process 15164 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzxGe6e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:15:06 INFO - PID 15164 | 16:15:06 INFO - PID 15164 | (/builds/slave/test/build/application/firefox/firefox:15217): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:15:06 INFO - PID 15164 | 16:15:16 INFO - PID 15164 | RSS: Main: 166817792 16:15:16 INFO - PID 15164 | 16:15:19 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57022/tests/kraken/driver.html?testName=audio-beat-detection) 16:15:20 INFO - PID 15164 | RSS: Main: 172695552 16:15:20 INFO - PID 15164 | 16:15:23 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57022/tests/kraken/driver.html?testName=audio-dft) 16:15:23 INFO - PID 15164 | RSS: Main: 173465600 16:15:23 INFO - PID 15164 | 16:15:26 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57022/tests/kraken/driver.html?testName=audio-fft) 16:15:27 INFO - PID 15164 | RSS: Main: 167821312 16:15:27 INFO - PID 15164 | 16:15:29 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57022/tests/kraken/driver.html?testName=audio-oscillator) 16:15:30 INFO - PID 15164 | RSS: Main: 168210432 16:15:30 INFO - PID 15164 | 16:15:32 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57022/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:15:32 INFO - PID 15164 | RSS: Main: 169127936 16:15:32 INFO - PID 15164 | 16:15:36 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57022/tests/kraken/driver.html?testName=imaging-darkroom) 16:15:36 INFO - PID 15164 | RSS: Main: 171454464 16:15:36 INFO - PID 15164 | 16:15:40 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57022/tests/kraken/driver.html?testName=imaging-desaturate) 16:15:40 INFO - PID 15164 | RSS: Main: 171446272 16:15:40 INFO - PID 15164 | 16:15:43 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57022/tests/kraken/driver.html?testName=json-parse-financial) 16:15:44 INFO - PID 15164 | RSS: Main: 171786240 16:15:44 INFO - PID 15164 | 16:15:45 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57022/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:15:46 INFO - PID 15164 | RSS: Main: 171790336 16:15:46 INFO - PID 15164 | 16:15:48 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57022/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:15:48 INFO - PID 15164 | RSS: Main: 171634688 16:15:48 INFO - PID 15164 | 16:15:50 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57022/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:15:51 INFO - PID 15164 | RSS: Main: 171888640 16:15:51 INFO - PID 15164 | 16:15:54 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57022/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:15:54 INFO - PID 15164 | RSS: Main: 171954176 16:15:54 INFO - PID 15164 | 16:15:57 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57022/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:15:58 INFO - PID 15164 | RSS: Main: 172220416 16:15:58 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | Cycle 1(1): loaded http://localhost:57022/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57022/tests/kraken/driver.html?testName=ai-astar) 16:16:00 INFO - PID 15164 | RSS: Main: 174084096 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | __start_tp_report 16:16:00 INFO - PID 15164 | _x_x_mozilla_page_load 16:16:00 INFO - PID 15164 | _x_x_mozilla_page_load_details 16:16:00 INFO - PID 15164 | |i|pagename|runs| 16:16:00 INFO - PID 15164 | |0;ai-astar;98;109;99;108;106;108;108;100;107;109 16:16:00 INFO - PID 15164 | |1;audio-beat-detection;144;118;151;165;115;142;144;116;173;122 16:16:00 INFO - PID 15164 | |2;audio-dft;164;162;162;161;163;162;163;165;169;163 16:16:00 INFO - PID 15164 | |3;audio-fft;74;74;74;173;77;74;75;76;157;74 16:16:00 INFO - PID 15164 | |4;audio-oscillator;80;80;132;79;79;79;122;79;80;134 16:16:00 INFO - PID 15164 | |5;imaging-gaussian-blur;114;114;114;114;114;117;115;114;114;114 16:16:00 INFO - PID 15164 | |6;imaging-darkroom;147;147;148;148;149;149;149;148;151;148 16:16:00 INFO - PID 15164 | |7;imaging-desaturate;93;94;95;94;94;94;94;95;95;94 16:16:00 INFO - PID 15164 | |8;json-parse-financial;53;59;53;52;52;52;53;58;54;53 16:16:00 INFO - PID 15164 | |9;json-stringify-tinderbox;39;38;38;38;38;38;39;39;38;38 16:16:00 INFO - PID 15164 | |10;stanford-crypto-aes;74;74;75;74;78;75;75;75;77;75 16:16:00 INFO - PID 15164 | |11;stanford-crypto-ccm;123;124;131;118;118;123;115;116;125;158 16:16:00 INFO - PID 15164 | |12;stanford-crypto-pbkdf2;180;168;179;189;173;171;171;169;174;177 16:16:00 INFO - PID 15164 | |13;stanford-crypto-sha256-iterative;69;66;66;63;74;62;65;67;63;64 16:16:00 INFO - PID 15164 | __end_tp_report 16:16:00 INFO - PID 15164 | __start_cc_report 16:16:00 INFO - PID 15164 | _x_x_mozilla_cycle_collect,952 16:16:00 INFO - PID 15164 | __end_cc_report 16:16:00 INFO - PID 15164 | __startTimestamp1504653360631__endTimestamp 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | ------- Summary: start ------- 16:16:00 INFO - PID 15164 | Number of tests: 14 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#0] ai-astar Cycles:10 Average:105.20 Median:108.50 stddev:4.39 (4.0%) stddev-sans-first:3.81 16:16:00 INFO - PID 15164 | Values: 98.0 109.0 99.0 108.0 106.0 108.0 108.0 100.0 107.0 109.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#1] audio-beat-detection Cycles:10 Average:139.00 Median:144.00 stddev:20.74 (14.4%) stddev-sans-first:21.92 16:16:00 INFO - PID 15164 | Values: 144.0 118.0 151.0 165.0 115.0 142.0 144.0 116.0 173.0 122.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.00 stddev:2.27 (1.4%) stddev-sans-first:2.40 16:16:00 INFO - PID 15164 | Values: 164.0 162.0 162.0 161.0 163.0 162.0 163.0 165.0 169.0 163.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#3] audio-fft Cycles:10 Average:92.80 Median:74.00 stddev:38.25 (51.7%) stddev-sans-first:39.96 16:16:00 INFO - PID 15164 | Values: 74.0 74.0 74.0 173.0 77.0 74.0 75.0 76.0 157.0 74.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.00 stddev:24.30 (30.8%) stddev-sans-first:25.21 16:16:00 INFO - PID 15164 | Values: 80.0 80.0 132.0 79.0 79.0 79.0 122.0 79.0 80.0 134.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.00 stddev:0.97 (0.8%) stddev-sans-first:1.01 16:16:00 INFO - PID 15164 | Values: 114.0 114.0 114.0 114.0 114.0 117.0 115.0 114.0 114.0 114.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:1.17 (0.8%) stddev-sans-first:1.13 16:16:00 INFO - PID 15164 | Values: 147.0 147.0 148.0 148.0 149.0 149.0 149.0 148.0 151.0 148.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 16:16:00 INFO - PID 15164 | Values: 93.0 94.0 95.0 94.0 94.0 94.0 94.0 95.0 95.0 94.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.00 stddev:2.51 (4.7%) stddev-sans-first:2.65 16:16:00 INFO - PID 15164 | Values: 53.0 59.0 53.0 52.0 52.0 52.0 53.0 58.0 54.0 53.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.44 16:16:00 INFO - PID 15164 | Values: 39.0 38.0 38.0 38.0 38.0 38.0 39.0 39.0 38.0 38.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:75.00 stddev:1.32 (1.8%) stddev-sans-first:1.32 16:16:00 INFO - PID 15164 | Values: 74.0 74.0 75.0 74.0 78.0 75.0 75.0 75.0 77.0 75.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:123.50 stddev:12.53 (10.1%) stddev-sans-first:13.27 16:16:00 INFO - PID 15164 | Values: 123.0 124.0 131.0 118.0 118.0 123.0 115.0 116.0 125.0 158.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.10 Median:175.50 stddev:6.35 (3.6%) stddev-sans-first:6.48 16:16:00 INFO - PID 15164 | Values: 180.0 168.0 179.0 189.0 173.0 171.0 171.0 169.0 174.0 177.0 16:16:00 INFO - PID 15164 | 16:16:00 INFO - PID 15164 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:3.54 (5.4%) stddev-sans-first:3.57 16:16:00 INFO - PID 15164 | Values: 69.0 66.0 66.0 63.0 74.0 62.0 65.0 67.0 63.0 64.0 16:16:00 INFO - PID 15164 | -------- Summary: end -------- 16:16:00 INFO - PID 15164 | 16:16:01 INFO - PID 15164 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:16:01 INFO - TEST-INFO | 15164: exit 0 16:16:01 INFO - TEST-OK | kraken | took 61662ms 16:16:01 INFO - SUITE-END | took 1170s 16:16:01 INFO - Completed test suite (00:19:31) 16:16:01 INFO - javascript benchmark 16:16:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8637.00346042516, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.2244472548946, 3316.088075779161, 3321.3357328534294, 3345.2138747394356, 3355.6476569960137, 6404.263509696386, 6408.166938298026, 6438.645874476902, 6486.416222539982, 6498.057781621668, 6234.314225435199, 6347.873042539147, 6358.61848289103, 6364.713587888545, 6366.0, 5778.0, 5782.595218334718, 5787.71061446927, 5807.419258074201, 5826.6212696174825, 5800.854978625526, 5826.300843898717, 5925.614835035709, 5936.64380137191, 5949.048152295633, 5933.970330148343, 5935.584509084344, 5982.521398288127, 6003.369646187153, 6071.666058366788, 5636.661800291996, 5785.161151633031, 5868.853278668024, 5909.940900590982, 6005.57960942732, 5789.8263052108505, 5803.390643982363, 5810.33181184165, 5818.0, 5891.175235467038, 6284.685765711696, 6385.904211436832, 6451.612903225821, 6468.159139311906, 6523.739050437976, 6659.533832631718, 6801.319868013208, 6928.549644273106, 6957.408620267283, 7049.189343225544, 6970.059042029312, 7057.435405167575, 7058.647067646597, 7063.646817659096, 7090.71637134514, 6439.130717353191, 6519.445847103002, 6521.184851893513, 6522.967385163091, 6538.346165383424, 7051.612161331176, 7124.821879452951, 7152.105986751656, 7225.674844632004, 7232.710691572278, 6620.602763834186, 6710.127683401127, 6773.0, 6846.041554182419, 6905.551139175937, 6894.207166175854, 6919.065926099912, 6923.515353925278, 7047.43620510358, 7051.682674279669, 6906.896396554056, 6925.134358205224, 6978.965105174543, 6993.930060699327, 7012.894806577905, 6943.72225111, 6950.270221626757, 6961.686724097428, 7064.858702826017, 7065.222825489295, 5303.204519322056, 5425.593080518976, 5448.291722076105, 5488.643238189505, 5556.6943818089585, 4751.762411879392, 4769.0, 4813.807447702053, 4825.469198388175, 4826.203676393426, 5750.6837123958585, 5840.386759390288, 5902.61632993854, 5935.020721580977, 5993.580449368503, 6879.0, 6894.896576551256, 6895.413889819321, 6896.7241060403985, 6898.655067246617, 5909.556783241274, 5934.0, 5937.495312898365, 5938.554608404387, 5946.672932988728, 5937.6734279614175, 6029.276486821609, 6034.094885767082, 6047.697615119227, 6049.21360223177, 6689.866202675919, 6725.024871393621, 6730.0577919091365, 6785.287544807823, 6803.897941530881, 6788.59268443895, 6850.349216824394, 6984.371406573433, 7045.0489183960535, 7068.0, 6648.767293144911, 6689.899651505134, 6703.4972377070935, 6735.797926062226, 6737.797866064026, 6793.626350550767, 6829.112215411965, 6860.0738900247825, 6959.130108736408, 6963.025176475398, 6975.162980442379, 6998.790036298919, 7067.328603782633, 7092.25531319204, 7097.325754053357, 6477.157969464128, 6595.274519802721, 6647.700853461509, 6713.832154196184, 6831.077804496528, 6699.229588597374, 6701.631410272287, 6709.832254193684, 6727.461803055841, 6758.831029224309, 6616.66916654175, 6623.039659249285, 6694.564853284521, 6697.0, 6710.261871194262, 6661.900071499028, 6777.864442711217, 6848.075509806312, 6850.520463567503, 6860.794176174722, 6340.65126418033, 6489.9351006491215, 6522.478201743754, 6631.403173714389, 6633.6019838809825], "value": 6300.8376517907445, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.2703959560239, 787.193127044779, 811.4319976016787, 819.8934138561985, 1129.2829053550993, 37106.62893371066, 40769.3884591731, 40862.59137408625, 41048.38427423591, 41071.794641026754, 40779.18441631173, 41182.588174118326, 41333.17333653333, 41541.37687934683, 41609.0, 7528.999999999986, 7600.239976002411, 7723.343515801135, 7790.298873101419, 7798.64906079225, 7683.807904802369, 7795.610219489031, 7798.337141342992, 7800.0, 7800.687972481094, 7725.806854828618, 7757.030371203599, 7786.0, 7797.883031754528, 7800.882986755175, 7717.845643087163, 7747.341475974521, 7752.0, 7758.844823103563, 7760.301572858441, 7675.846483070363, 7676.155622881479, 7712.460127791001, 7713.49862258957, 7723.266289702469, 36498.8175059123, 36899.4465083024, 36981.44527832085, 36989.815050924575, 37024.629753702386, 3479.860805567774, 3488.947665785015, 3518.66572675598, 3520.9471857922154, 3524.577050753926, 30037.249068773235, 30091.548626770615, 30235.395292094036, 30258.69741302581, 30261.394772104435, 4898.265260210961, 5077.136886729265, 5084.13569693153, 5112.233165025239, 5113.616478764107, 178231.0, 178566.0, 180195.0, 180407.0, 180826.19173808224, 45550.0, 45556.77221613937, 45696.3145552817, 45779.31331030037, 45835.31247031297, 48003.75998120057, 48181.5181848177, 48212.27681584779, 48263.27605085926, 48268.517314827106, 11975.341356225492, 12214.206076604993, 14153.150810951376, 14499.130052196902, 14500.927495362666, 10241.7709874814, 10457.215708821717, 10473.685789426178, 10475.580976760997, 10505.947470262752, 13638.795418068737, 13755.17468951866, 13789.379477923518, 13789.931050344683, 13910.165390076425, 5551.722413879371, 5623.522000629992, 5634.352049514277, 5680.233168522279, 5723.713814309268, 990.4700984973005, 992.8262554053011, 993.8062077894886, 1016.9644062457778, 1017.7862648843677, 22073.558528829333, 22599.887000564893, 22717.0, 22791.658125128135, 23024.769752302596, 1085.571199376242, 1093.1200383691205, 1096.6107032003683, 1099.0603034405628, 1108.4291589831244, 11822.940885295518, 11900.404979750978, 12095.81856272139, 12169.634910952685, 12176.0, 222485.0, 224459.0, 225163.0, 225261.0, 225992.0, 48688.26967595489, 49053.75473122683, 49071.509284907406, 49249.26126108111, 49387.50612493829, 1391.6729568551382, 1412.773956167029, 1435.6052085676522, 1436.906601070927, 1439.9208043557496, 22441.887790560944, 22521.211757588404, 22580.661290080334, 22655.88672056629, 22833.543329132994, 36213.0, 36292.81853590715, 36294.455583166804, 36300.0, 36375.0, 187221.0, 187595.0, 187710.0, 187850.0, 187853.0, 32983.17542061468, 33134.66865331316, 35136.82431587826, 35878.0, 35910.28179436344, 45533.31700024436, 46148.76925615351, 46392.0, 46447.0, 46562.767186165205, 33070.17324566905, 33590.83204583961, 33745.83127084349, 34279.48580771241, 34396.65603343934, 33404.167433897615, 33415.33169336648, 33571.66428335783, 33597.32805343831, 34016.0, 37766.62233377631, 37965.24069518649, 38099.42850857184, 38111.80944095262, 38216.235675286895, 32515.34969300648, 32570.83714581412, 32818.0, 33641.3271734559, 33818.0, 24201.515969680157, 24628.50742985166, 24990.375240618403, 25390.746092538837, 25456.490870182864], "value": 16834.179844035996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.8170844475544, 563.636454486856, 572.3418069220397, 574.6867956963455, 575.9251297331347, 21739.23912663057, 22616.77383226167, 22641.433964150863, 22649.54700905981, 22686.88656556717, 23579.88210058947, 25937.22188334348, 25975.480490390182, 26043.86978065112, 26081.34796630085, 6638.70125844337, 6784.864302713942, 6786.660666966656, 6789.558678685894, 6805.55763875349, 6526.0, 6718.563293385914, 6781.355771201728, 7092.255313192118, 7102.0, 7100.183478899941, 7114.715411383564, 7117.466190035742, 7117.644117794115, 7117.85764284714, 7125.572465652078, 7128.215896251409, 7159.642017899084, 7159.961805538193, 7164.247753985861, 6992.510524263253, 6996.965015174892, 6999.650017499104, 7008.369246767765, 7025.121859767529, 30138.397232055235, 30565.541516877263, 30679.38641227185, 30824.53763193554, 30865.074047778602, 2093.665013597832, 2120.4698825293676, 2121.193946300396, 2122.0, 2127.0853532980814, 20386.49033774153, 20978.5804283915, 21001.5799684007, 21032.0, 21058.894705526527, 2390.9001859144814, 2436.074291769134, 2512.623106534016, 2553.6807899012624, 2559.912037384123, 131919.34040329736, 132739.0, 135732.0, 136497.3175134128, 136676.0, 19315.04041671454, 20300.28948986808, 20601.48496287605, 21411.8929405352, 22177.778222217894, 40138.197236055115, 40182.1963560733, 40204.0, 40225.798871005456, 40308.79845600812, 9307.441553506676, 9489.525523723787, 9495.857562136573, 9595.616175353047, 9690.95154524223, 7554.584497852671, 7655.846883062308, 7672.079350477867, 7692.730754423568, 7762.883556746653, 8936.078743070271, 10335.793284134426, 10792.028717415313, 11255.606053788078, 11301.547938082549, 3639.399499082622, 3778.886633401002, 3987.3620220764537, 4122.898504438733, 4181.456410666655, 928.8467402878584, 948.9810203796023, 973.9466771670862, 986.8470387090013, 993.7416271769248, 19628.607427851362, 19659.213631454866, 19909.900450497655, 20007.59984800296, 20210.696839547418, 989.5200827598609, 1025.74869157057, 1031.169908223872, 1031.5300696507447, 1044.0968562193648, 10418.426986515815, 10918.344899306067, 10938.890611093946, 11082.778344433065, 11138.49876755548, 455517.0, 480194.0, 489087.0, 489617.0, 495971.0, 108625.0, 113885.4305728466, 113889.43055284671, 115709.0, 115830.0, 1106.0985296982933, 1122.7529943412437, 1148.4028305281206, 1150.6663067710438, 1156.346664134778, 17241.137943103047, 17252.827471725624, 17513.824861751218, 17892.642147156723, 17894.28422863071, 51951.48048519466, 54306.09928652478, 54355.45644543504, 55204.44795552154, 55285.44714552803, 63857.68071159614, 64240.35759642531, 64480.0, 65545.0, 71822.64088679523, 12456.252624842538, 12681.112322137362, 14154.221517816619, 14497.840172786362, 15034.774478383051, 23150.768492315536, 23515.52968940646, 23860.88069559641, 24448.14431494889, 24742.134025309027, 18955.14701838393, 19036.904815475835, 19305.8069419304, 19345.61308773845, 19349.709754353415, 16611.66776664484, 16928.49214523566, 17075.40236091731, 17113.74329385085, 17377.218025188646, 19462.221511139396, 19601.60796784085, 19710.40868773939, 19820.90089549543, 19892.403227903185, 14296.642583935069, 14620.780688289471, 14621.63445913818, 14631.195284259467, 14648.853511465175, 18278.177482013074, 18534.629307414045, 18545.81454185495, 18550.165242563846, 18551.0], "value": 12488.189185165435, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.4342117757599, 1327.4889167670444, 1346.9259190744506, 1349.932503374831, 1352.9661758456034, 14732.705345893075, 15076.773848392282, 15452.381904723798, 15460.922695386522, 15494.0, 15744.29150688219, 15965.521034368958, 15980.0, 15996.040237585754, 16009.359625614961, 2628.776553992913, 2691.24645099372, 2746.2722378569697, 2746.997345968727, 2750.7661848742882, 2705.2290097322266, 2736.097087960978, 2741.136541989277, 2741.2461573067367, 2742.437800250954, 2712.050782226225, 2724.632174656426, 2725.1143378402, 2739.643846299988, 2744.2041807875694, 2589.313831834556, 2596.7273436289106, 2603.648507451489, 2610.0, 2614.725453827349, 2552.6681531400986, 2554.859482728449, 2575.9098431554808, 2575.935601609956, 2577.510273048115, 14858.0, 15054.623634409118, 15085.24573771307, 15179.16514591697, 15182.696346073017, 7676.270754278335, 7976.7208147714555, 7979.281864632153, 7982.042154941444, 7988.480748751313, 5034.546890779848, 5243.370795504564, 5357.544608708265, 5362.839114826562, 5365.946340536584, 1883.3031778242, 1935.6128774245208, 1953.6483432982056, 1988.721578978944, 2000.8399328053865, 24988.625170622454, 25823.35441613956, 26063.218103456926, 26065.478690426084, 26558.6016209757, 6577.177852768308, 6691.933080669229, 6786.117804685359, 6857.451403887677, 6908.930910690829, 16572.591329737108, 17015.23431445588, 17058.402955896483, 17098.572535686457, 17494.65010699779, 7565.659545320474, 7831.960840195762, 7842.45102842807, 7846.803829904184, 7850.2542258485355, 7051.294870512907, 7159.92840071603, 7180.3537681608905, 7215.711371545184, 7230.710771569184, 4973.776180071905, 5096.311997880312, 5113.974430127825, 5125.103106956342, 5171.81898633546, 1450.5358285348798, 1454.2292584930003, 1461.3935216884875, 1472.7569950957973, 1473.1971075763734, 879.0, 910.39003867409, 913.36064754672, 914.8993610702818, 921.2537844346101, 7761.4566980311965, 7772.339351155102, 7799.454038217384, 7817.218278172138, 7837.725679601187, 956.297121615612, 1006.8691070160833, 1009.7627057641448, 1010.6109147978082, 1013.8986101389802, 5562.221289019541, 5621.184928185472, 5630.352509461382, 5683.0338842396895, 5715.942840571624, 21506.247281345077, 21793.23723669664, 21867.343979680634, 21940.670889936664, 22201.555968880533, 20898.37304880856, 21153.89423052906, 21320.0, 21323.893380533307, 21899.890500547397, 11066.39134847591, 11481.827772583418, 11499.712507187387, 11544.769104618028, 11584.710382240342, 8201.13888041747, 8282.296004839656, 8401.83196336082, 8443.324534037385, 8585.313174946114, 18056.819431805514, 18095.63808723792, 18467.81532184661, 18751.90624046871, 18932.242710291986, 18790.624187516445, 19118.426447206606, 19446.902765486084, 19522.316718914768, 19659.213631454582, 10406.063454289151, 10636.680899573024, 10655.0, 10655.520501577292, 10659.573617055541, 13964.2319672419, 14085.647858803612, 14163.221022843842, 14226.075305105134, 14281.071730337495, 11169.776604467703, 11248.94375528117, 11362.261453005529, 11423.828642570534, 11454.08367330595, 11065.225434219527, 11132.777344453227, 11252.099832013255, 11272.0, 11401.543938242377, 10195.133413659922, 10214.233932455245, 10228.232882533692, 10239.232057595562, 10289.125424339016, 7703.345215656508, 7756.108047574601, 7800.102988156208, 7818.687252509836, 7837.451378403457, 7338.926610734038, 7681.69273229087, 7688.46180767341, 7692.730754423568, 7696.8460630788195], "value": 6680.197265355911, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.49558876927165, 511.7364557253014, 531.7367902888071, 535.1597991153889, 540.1789280293954, 6346.904796428052, 6378.585391949531, 6397.385477205382, 6401.807945761635, 6417.35826417358, 6209.161763161983, 6233.503959049829, 6271.905921411171, 6312.715927783261, 6375.808725738224, 5185.377754669445, 5219.9478005219935, 5249.86875328116, 5256.421793602701, 5279.366476022863, 4905.847125925405, 5018.924716129242, 5068.949310506903, 5154.123798954187, 5191.714455704935, 4983.405310300705, 4999.325091112708, 5046.571041461461, 5056.949430505685, 5061.873453163664, 4993.700377977333, 4998.90002199954, 4999.175136102539, 5044.949550504503, 5070.6957582545165, 4426.18115648606, 4506.707064040859, 4530.365748795171, 4532.750698711569, 4551.249043907751, 6128.540359473057, 6143.017117261262, 6186.7525298987985, 6196.256449226076, 6239.344868788802, 5620.864071652787, 5629.408912064257, 5689.203511508392, 5697.857553561194, 5738.454846789548, 6542.906269993604, 6553.377429144223, 6631.237407698129, 6640.7343706251695, 6660.9000864987065, 4913.926291105637, 4921.18800397934, 4921.753912304406, 4947.579455746266, 4955.306257124005, 6611.636360000198, 6806.659667016679, 6843.349881761224, 6871.484638652121, 6953.4354770176815, 4971.328870602422, 5010.574101201439, 5016.849494515171, 5066.746662666852, 5078.162103252996, 5105.927755171456, 5112.821051263262, 5153.7165455900285, 5161.845144645667, 5165.535101840853, 5702.857428564319, 5720.770034442531, 5737.536928083396, 5750.712464376765, 5793.855153621109, 6583.979483180115, 6598.934010659928, 6609.239937407164, 6640.468762498988, 6726.697298621574, 1760.9082368931345, 1768.9115544222996, 1785.749995000701, 1788.8837225580296, 1813.610073834132, 1143.3482914738686, 1217.2088142707134, 1225.822255772908, 1235.734317121823, 1243.5461056714234, 5839.211706419579, 5897.675627840425, 5900.262467191601, 5938.6139900907165, 5948.018576934757, 6666.899996500057, 6736.360045795642, 6862.348076932683, 6890.1741038624805, 6914.545336405504, 5734.942650573441, 5754.1944127822135, 5790.971045144832, 5791.797287095016, 5907.320658124285, 3630.1832087780567, 3634.3639863023864, 3653.1962968146963, 3667.587978628194, 3674.595794462607, 6556.213254409501, 6596.241432235259, 6665.166854143232, 6685.565438246498, 6741.426978706767, 6391.904121438182, 6794.4904132189295, 7042.647867606599, 7061.858762824715, 7104.396126329217, 6412.396900774806, 6427.646479443675, 6462.741490340334, 6539.5422320438065, 6566.832945128001, 6534.509911756542, 6619.271880093283, 6681.933180668131, 6741.146374610273, 6894.070379883618, 6681.398674119353, 6698.497612678971, 6729.596224226562, 6731.653669266264, 6881.243063262937, 6522.043386983981, 6524.902126468011, 6560.147180866456, 6627.271000190071, 6662.766803161866, 4897.673605037721, 5024.648274620741, 5025.246213068069, 5082.974585127198, 5088.440271570198, 6565.540412171103, 6710.101625813086, 6808.319168083152, 6835.350641689031, 6855.280195579392, 6338.3729877190835, 6539.901901471577, 6573.408393244631, 6593.274739778716, 6691.126484584324, 6458.967705161444, 6508.0, 6608.768693095911, 6610.0, 6653.800385988428, 6481.675916204265, 6535.215774107138, 6560.40956313934, 6585.012248162814, 6627.867442651023, 6720.260771315054, 6737.393634572912, 6750.45996320303, 6779.728810847708, 6783.287754785873, 6445.935540644534, 6446.8388290293115, 6487.3188315226225, 6502.219733631994, 6531.248906375828], "value": 5115.820790089465, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [898.7483504618709, 907.369378282094, 923.5290002098927, 926.4950601921952, 934.4953724988507, 18574.25702971881, 19586.706199406985, 19640.116194771235, 19654.508637284056, 22369.888150559225, 20931.895340523275, 21355.466113347135, 21417.14331426741, 21444.785552144513, 21456.0, 982.5480279071618, 1004.1264100232808, 1007.8488226766007, 1008.0927165551026, 1008.1330056151704, 954.7135859242235, 971.2327261463436, 971.9076687714655, 976.677696360203, 985.7042887133867, 1044.8537204791335, 1050.800347933894, 1051.8842927277992, 1052.147760314148, 1054.8892366301543, 1037.4708898461802, 1044.7074819050601, 1045.2578669144916, 1045.2839804733744, 1045.806525792727, 763.1147868472545, 765.2271206081841, 768.2739810878721, 773.8916551682769, 774.0324594257179, 19802.306919257902, 19949.30177443797, 20045.799542004686, 20059.799402006083, 20416.183352666023, 20985.265515706877, 21168.364949051553, 21588.136474541, 21595.0, 21607.0, 16983.3206671733, 17259.13704314792, 17345.0, 17429.38997135094, 17450.127493625267, 3806.352920003606, 3838.7504812187117, 3839.9424008639894, 3840.481534992768, 3840.539135303781, 129921.0, 130104.349478252, 130859.34570327183, 134223.0, 134413.0, 16466.0, 16818.327266909186, 17049.829501705073, 17324.566885827702, 17552.64894702099, 30606.846965765028, 31319.0, 31694.524582131286, 31988.0, 32197.839010805266, 7193.89209161863, 7480.364169045583, 8856.690015849414, 9213.861792073125, 9423.34036617444, 4244.830206791694, 4352.173087113439, 4357.607815296638, 4361.760103194355, 4363.847265345697, 5174.327337446108, 5269.1832765921345, 5304.38999515053, 5320.733963301819, 5335.653182543122, 1036.4713995862164, 1057.582255009267, 1063.9095676867553, 1074.5809134437575, 1074.6185104288022, 662.5991275278484, 663.3234101216829, 679.1035832700886, 689.8723736108836, 690.378659206718, 11708.356040417688, 12668.239905605693, 13614.387352569158, 13766.518171864138, 14268.357923893449, 682.4847240333517, 706.2364352442704, 707.4481904114799, 717.7129148340601, 721.6752461392394, 5524.447555244444, 5833.854153646193, 5955.583109182402, 6062.878367502026, 6076.513878889678, 104149.0, 107558.46220769003, 109110.45444772726, 113071.47199824564, 114051.42974285076, 95959.52020239994, 96421.51789241009, 97900.0, 99093.50453247687, 100358.0, 79682.0, 80576.19423805804, 80860.0, 81345.18654813494, 82136.0, 13100.082994190316, 13285.13646612967, 13297.468101275841, 13334.26661533625, 13362.532311369056, 39058.80470597629, 39963.20073598454, 40380.59619403886, 40437.79781101076, 40774.79612601918, 50699.0, 51137.178363738225, 51151.48848511467, 51516.0, 52949.735251323495, 17809.287628494716, 18283.360082397052, 18315.908420457814, 18328.083595820422, 18336.0, 16745.665086698442, 20716.171353145706, 21056.68414973746, 21129.788702112783, 21193.78806211918, 17187.31250749956, 17416.91291543585, 17547.385841495485, 17599.384021559184, 17604.647907042043, 17362.13189340553, 17463.0, 17524.47426577205, 17616.91191544034, 17633.73549396786, 17778.199981000627, 18832.62334753325, 18895.716564251274, 19080.8091919079, 19191.616167676846, 11953.760924781629, 12645.494180233056, 12789.93605031969, 13000.804987925, 13009.349532523525, 13826.723465530835, 13855.515056972958, 13898.51355202563, 13918.930405347908, 14005.579832605039], "value": 9169.785289816555, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1485.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 99.0, 108.0, 106.0, 108.0, 108.0, 100.0, 107.0, 109.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 118.0, 151.0, 165.0, 115.0, 142.0, 144.0, 116.0, 173.0, 122.0], "value": 139.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 161.0, 163.0, 162.0, 163.0, 165.0, 169.0, 163.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 173.0, 77.0, 74.0, 75.0, 76.0, 157.0, 74.0], "value": 92.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 132.0, 79.0, 79.0, 79.0, 122.0, 79.0, 80.0, 134.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 117.0, 115.0, 114.0, 114.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 148.0, 149.0, 149.0, 149.0, 148.0, 151.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 59.0, 53.0, 52.0, 52.0, 52.0, 53.0, 58.0, 54.0, 53.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 39.0, 38.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 74.0, 78.0, 75.0, 75.0, 75.0, 77.0, 75.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 124.0, 131.0, 118.0, 118.0, 123.0, 115.0, 116.0, 125.0, 158.0], "value": 125.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 168.0, 179.0, 189.0, 173.0, 171.0, 171.0, 169.0, 174.0, 177.0], "value": 175.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 66.0, 63.0, 74.0, 62.0, 65.0, 67.0, 63.0, 64.0], "value": 65.9, "unit": "score"}]}]} 16:16:01 INFO - Return code: 0 16:16:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:16:01 INFO - # TBPL SUCCESS # 16:16:01 INFO - Running post-action listener: _package_coverage_data 16:16:01 INFO - Running post-action listener: _resource_record_post_action 16:16:01 INFO - [mozharness: 2017-09-05 23:16:01.903406Z] Finished run-tests step (success) 16:16:01 INFO - Running post-run listener: _resource_record_post_run 16:16:01 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 398180352; Read time: 112; Write time: 255268 16:16:01 INFO - TinderboxPrint: CPU usage
13.1% 16:16:01 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 112 16:16:01 INFO - TinderboxPrint: I/O write bytes / time
398,180,352 / 255,268 16:16:01 INFO - TinderboxPrint: CPU idle
8,229.3 (86.9%) 16:16:01 INFO - TinderboxPrint: CPU user
1,191.6 (12.6%) 16:16:01 INFO - TinderboxPrint: Swap in / out
0 / 0 16:16:01 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 113590272; Read time: 0; Write time: 83328 16:16:01 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:16:01 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 283734016; Read time: 112; Write time: 171904 16:16:02 INFO - Running post-run listener: _upload_blobber_files 16:16:02 INFO - Blob upload gear active. 16:16:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:16:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:16:02 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'] 16:16:02 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 16:16:02 INFO - (blobuploader) - INFO - Open directory for files ... 16:16:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:16:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:16:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:09 INFO - (blobuploader) - INFO - Done attempting. 16:16:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:16:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:16:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:10 INFO - (blobuploader) - INFO - Done attempting. 16:16:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:16:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:16:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:11 INFO - (blobuploader) - INFO - Done attempting. 16:16:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:16:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:16:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:16:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:16:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:16:12 INFO - (blobuploader) - INFO - Done attempting. 16:16:12 INFO - (blobuploader) - INFO - Iteration through files over. 16:16:12 INFO - Return code: 0 16:16:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:16:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:16:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e74d687e1d25a6d45b6c1d5e1e3ab0c074b0c75679946d70ecc85a88f1afa6ce256afcec174dd49c23887eeb6e4c093328e886c5d8ef5d6502d9e615b15672a4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13fd35f6635ce303816db6e9d07ce11c9410960cf3b91aeca6454f1d5367fb6aab9ec19d68c7dfc3bf7afcfd915bbfe4a8261dc82091e874a89379940878457", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b12c71129af4d8b21634a63a2dcbda8ab0b32fb989b69c589c18f1ea87d755b7af8c51443ad61daf370716d7773b54b103947c771168f2a246189df8b33cc49", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32a4361ab2b45e72a36e518435d52767263ccc028d48626e56a6d02b3a327552fec7a0379c9754dcb0dbb04e103e6e9fca2d600d5de2c832bfea6c0d029b81d7"} 16:16:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:16:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:16:12 INFO - Contents: 16:16:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e74d687e1d25a6d45b6c1d5e1e3ab0c074b0c75679946d70ecc85a88f1afa6ce256afcec174dd49c23887eeb6e4c093328e886c5d8ef5d6502d9e615b15672a4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13fd35f6635ce303816db6e9d07ce11c9410960cf3b91aeca6454f1d5367fb6aab9ec19d68c7dfc3bf7afcfd915bbfe4a8261dc82091e874a89379940878457", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b12c71129af4d8b21634a63a2dcbda8ab0b32fb989b69c589c18f1ea87d755b7af8c51443ad61daf370716d7773b54b103947c771168f2a246189df8b33cc49", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32a4361ab2b45e72a36e518435d52767263ccc028d48626e56a6d02b3a327552fec7a0379c9754dcb0dbb04e103e6e9fca2d600d5de2c832bfea6c0d029b81d7"} 16:16:12 INFO - Running post-run listener: copy_logs_to_upload_dir 16:16:12 INFO - Copying logs to upload dir... 16:16:12 INFO - mkdir: /builds/slave/test/build/upload/logs 16:16:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.247132 ========= master_lag: 0.18 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-09-05 16:16:12.289927) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:16:12.295378) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e74d687e1d25a6d45b6c1d5e1e3ab0c074b0c75679946d70ecc85a88f1afa6ce256afcec174dd49c23887eeb6e4c093328e886c5d8ef5d6502d9e615b15672a4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13fd35f6635ce303816db6e9d07ce11c9410960cf3b91aeca6454f1d5367fb6aab9ec19d68c7dfc3bf7afcfd915bbfe4a8261dc82091e874a89379940878457", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b12c71129af4d8b21634a63a2dcbda8ab0b32fb989b69c589c18f1ea87d755b7af8c51443ad61daf370716d7773b54b103947c771168f2a246189df8b33cc49", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32a4361ab2b45e72a36e518435d52767263ccc028d48626e56a6d02b3a327552fec7a0379c9754dcb0dbb04e103e6e9fca2d600d5de2c832bfea6c0d029b81d7"} build_url:https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e74d687e1d25a6d45b6c1d5e1e3ab0c074b0c75679946d70ecc85a88f1afa6ce256afcec174dd49c23887eeb6e4c093328e886c5d8ef5d6502d9e615b15672a4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f13fd35f6635ce303816db6e9d07ce11c9410960cf3b91aeca6454f1d5367fb6aab9ec19d68c7dfc3bf7afcfd915bbfe4a8261dc82091e874a89379940878457", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9b12c71129af4d8b21634a63a2dcbda8ab0b32fb989b69c589c18f1ea87d755b7af8c51443ad61daf370716d7773b54b103947c771168f2a246189df8b33cc49", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32a4361ab2b45e72a36e518435d52767263ccc028d48626e56a6d02b3a327552fec7a0379c9754dcb0dbb04e103e6e9fca2d600d5de2c832bfea6c0d029b81d7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:16:12.325058) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:16:12.325352) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1504652113.296895-88854301 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004593 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:16:12.374961) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:16:12.376301) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:16:12.376599) ========= ========= Total master_lag: 0.31 =========