builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-017 starttime: 1504651556.39 results: success (0) revision: 978d2539a8d1a49e9f9705204f3918772b337547 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:56.394729) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:56.395197) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:56.395466) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:56.413260) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:56.413596) ========= 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=0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 15:45:56-- 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% 324K=0.04s 2017-09-05 15:45:56 (324 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.196283 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:56.626591) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:56.626912) ========= 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=0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019572 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:56.679968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:56.683340) ========= 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=0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 15:45:56,967 truncating revision to first 12 chars 2017-09-05 15:45:56,967 Setting DEBUG logging. 2017-09-05 15:45:56,967 attempt 1/10 2017-09-05 15:45:56,967 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:45:57,418 unpacking tar archive at: mozilla-central-978d2539a8d1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.762678 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:57.465160) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:57.465496) ========= script_repo_revision: 978d2539a8d1a49e9f9705204f3918772b337547 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:57.465875) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:57.466145) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 15:45:57.479590) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-05 15:45:57.479983) ========= /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=0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:45:57 INFO - MultiFileLogger online at 20170905 15:45:57 in /builds/slave/test 15:45:57 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:45:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:45:57 INFO - {'append_to_log': False, 15:45:57 INFO - 'base_work_dir': '/builds/slave/test', 15:45:57 INFO - 'blob_upload_branch': 'Firefox', 15:45:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:45:57 INFO - 'branch': 'Firefox', 15:45:57 INFO - 'buildbot_json_path': 'buildprops.json', 15:45:57 INFO - 'code_coverage': False, 15:45:57 INFO - 'config_files': ('talos/linux_config.py',), 15:45:57 INFO - 'default_actions': ('clobber', 15:45:57 INFO - 'read-buildbot-config', 15:45:57 INFO - 'download-and-extract', 15:45:57 INFO - 'populate-webroot', 15:45:57 INFO - 'create-virtualenv', 15:45:57 INFO - 'install', 15:45:57 INFO - 'setup-mitmproxy', 15:45:57 INFO - 'run-tests'), 15:45:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:45:57 INFO - 'disable_ccov_upload': False, 15:45:57 INFO - 'disable_stylo': False, 15:45:57 INFO - 'download_minidump_stackwalk': True, 15:45:57 INFO - 'download_symbols': 'ondemand', 15:45:57 INFO - 'enable_stylo': False, 15:45:57 INFO - 'enable_webrender': False, 15:45:57 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:45:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:45:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:45:57 INFO - 'gecko_profile': False, 15:45:57 INFO - 'gecko_profile_interval': 0, 15:45:57 INFO - 'installer_path': 'installer.exe', 15:45:57 INFO - 'log_level': 'info', 15:45:57 INFO - 'log_name': 'talos', 15:45:57 INFO - 'log_to_console': True, 15:45:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:45:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:45:57 INFO - 'opt_config_files': (), 15:45:57 INFO - 'pip_index': False, 15:45:57 INFO - 'suite': 'dromaeojs-e10s', 15:45:57 INFO - 'system_bits': '32', 15:45:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:45:57 INFO - 'title': 'talos-linux64-ix-017', 15:45:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:45:57 INFO - 'use_talos_json': True, 15:45:57 INFO - 'verify': 'False', 15:45:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:45:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:45:57 INFO - 'work_dir': 'build'} 15:45:57 INFO - [mozharness: 2017-09-05 22:45:57.918472Z] Running clobber step. 15:45:57 INFO - Running pre-action listener: _resource_record_pre_action 15:45:57 INFO - Running main action method: clobber 15:45:57 INFO - rmtree: /builds/slave/test/build 15:45:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:45:59 INFO - Running post-action listener: _resource_record_post_action 15:45:59 INFO - [mozharness: 2017-09-05 22:45:59.531477Z] Finished clobber step (success) 15:45:59 INFO - [mozharness: 2017-09-05 22:45:59.531575Z] Running read-buildbot-config step. 15:45:59 INFO - Running pre-action listener: _resource_record_pre_action 15:45:59 INFO - Running main action method: read_buildbot_config 15:45:59 INFO - Using buildbot properties: 15:45:59 INFO - { 15:45:59 INFO - "project": "", 15:45:59 INFO - "product": "firefox", 15:45:59 INFO - "who": "archaeopteryx@coole-files.de", 15:45:59 INFO - "installer_path": "public/build/target.tar.bz2", 15:45:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 15:45:59 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:45:59 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 15:45:59 INFO - "stage_platform": "linux64-stylo", 15:45:59 INFO - "basedir": "/builds/slave/test", 15:45:59 INFO - "buildnumber": 75, 15:45:59 INFO - "platform": "ubuntu64_hw_stylo", 15:45:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:45:59 INFO - "slavebuilddir": "test", 15:45:59 INFO - "taskId": "D9CexQgNQrKRWzw_K_XRJQ", 15:45:59 INFO - "branch": "mozilla-central", 15:45:59 INFO - "script_repo_revision": "production", 15:45:59 INFO - "revision": "978d2539a8d1a49e9f9705204f3918772b337547", 15:45:59 INFO - "slavename": "talos-linux64-ix-017", 15:45:59 INFO - "repo_path": "mozilla-central" 15:45:59 INFO - } 15:45:59 INFO - Finding installer, test and symbols from parent task. 15:45:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D9CexQgNQrKRWzw_K_XRJQ'}, attempt #1 15:45:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/D9CexQgNQrKRWzw_K_XRJQ'}, attempt #1 15:46:00 INFO - Task dependencies: ciQLVfAyTVS5ODxEPFePfg 15:46:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg'}, attempt #1 15:46:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 15:46:00 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.test_packages.json 15:46:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.crashreporter-symbols.zip 15:46:00 INFO - Running post-action listener: _resource_record_post_action 15:46:00 INFO - [mozharness: 2017-09-05 22:46:00.886751Z] Finished read-buildbot-config step (success) 15:46:00 INFO - [mozharness: 2017-09-05 22:46:00.886971Z] Running download-and-extract step. 15:46:00 INFO - Running pre-action listener: _resource_record_pre_action 15:46:00 INFO - Running main action method: download_and_extract 15:46:00 INFO - mkdir: /builds/slave/test/build/tests 15:46:00 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:46:00 INFO - trying https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.test_packages.json 15:46:00 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:46:00 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:46:01 INFO - Downloaded 989 bytes. 15:46:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:46:01 INFO - Using the following test package requirements: 15:46:01 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:46:01 INFO - u'common': [u'target.common.tests.zip'], 15:46:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:46:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:46:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:46:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:46:01 INFO - u'mozbase': [u'target.common.tests.zip'], 15:46:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:46:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:46:01 INFO - u'web-platform': [u'target.common.tests.zip', 15:46:01 INFO - u'target.web-platform.tests.tar.gz'], 15:46:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:46:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:46:01 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:46:01 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:46:01 INFO - Fetch https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.common.tests.zip into memory 15:46:02 INFO - Content-Length response header: 55019957 15:46:02 INFO - Bytes received: 55019957 15:46:13 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:46:13 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:46:13 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:46:13 INFO - Fetch https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.common.tests.zip into memory 15:46:14 INFO - Content-Length response header: 55019957 15:46:14 INFO - Bytes received: 55019957 15:46:26 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:46:26 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:46:26 INFO - Fetch https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.talos.tests.zip into memory 15:46:27 INFO - Content-Length response header: 14496654 15:46:27 INFO - Bytes received: 14496654 15:46:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:27 INFO - trying https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 15:46:27 INFO - Downloading https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:46:27 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:46:28 INFO - Downloaded 62666628 bytes. 15:46:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 15:46:28 INFO - mkdir: /builds/slave/test/properties 15:46:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:46:28 INFO - Writing to file /builds/slave/test/properties/build_url 15:46:28 INFO - Contents: 15:46:28 INFO - build_url:https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 15:46:28 INFO - Running post-action listener: _resource_record_post_action 15:46:28 INFO - Running post-action listener: find_tests_for_verification 15:46:28 INFO - Running post-action listener: set_extra_try_arguments 15:46:28 INFO - [mozharness: 2017-09-05 22:46:28.897807Z] Finished download-and-extract step (success) 15:46:28 INFO - [mozharness: 2017-09-05 22:46:28.897904Z] Running populate-webroot step. 15:46:28 INFO - Running pre-action listener: _resource_record_pre_action 15:46:28 INFO - Running main action method: populate_webroot 15:46:28 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:46:28 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:46:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:46:28 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:46:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:46:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:28 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:28 INFO - 'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:46:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:46:28 INFO - 'tests': ['damp', 'tps']}, 15:46:28 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:28 INFO - 'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['damp', 'tps']}, 15:46:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:46:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:46:28 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:46:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:46:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:46:28 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:28 INFO - 'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:46:28 INFO - 'other-e10s': {'tests': ['a11yr', 15:46:28 INFO - 'ts_paint', 15:46:28 INFO - 'tpaint', 15:46:28 INFO - 'sessionrestore', 15:46:28 INFO - 'sessionrestore_many_windows', 15:46:28 INFO - 'sessionrestore_no_auto_restore', 15:46:28 INFO - 'tabpaint']}, 15:46:28 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['a11yr', 15:46:28 INFO - 'ts_paint', 15:46:28 INFO - 'tpaint', 15:46:28 INFO - 'sessionrestore', 15:46:28 INFO - 'sessionrestore_many_windows', 15:46:28 INFO - 'sessionrestore_no_auto_restore', 15:46:28 INFO - 'tabpaint']}, 15:46:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:46:28 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:46:28 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['bloom_basic_singleton']}, 15:46:28 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['bloom_basic']}, 15:46:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:46:28 INFO - 'tsvgr_opacity', 15:46:28 INFO - 'tart', 15:46:28 INFO - 'tscrollx', 15:46:28 INFO - 'tsvg_static']}, 15:46:28 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['tsvgx', 15:46:28 INFO - 'tsvgr_opacity', 15:46:28 INFO - 'tart', 15:46:28 INFO - 'tscrollx', 15:46:28 INFO - 'tsvg_static']}, 15:46:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:46:28 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:28 INFO - 'talos_options': ['--enable-stylo'], 15:46:28 INFO - 'tests': ['tp5o']}, 15:46:28 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:28 INFO - 'talos_options': ['--mitmproxy', 15:46:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:28 INFO - '--firstNonBlankPaint'], 15:46:28 INFO - 'tests': ['tp6_google', 15:46:28 INFO - 'tp6_youtube', 15:46:28 INFO - 'tp6_amazon', 15:46:28 INFO - 'tp6_facebook']}, 15:46:28 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:28 INFO - 'talos_options': ['--enable-stylo', 15:46:28 INFO - '--mitmproxy', 15:46:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:28 INFO - '--firstNonBlankPaint'], 15:46:28 INFO - 'tests': ['tp6_google', 15:46:28 INFO - 'tp6_youtube', 15:46:28 INFO - 'tp6_amazon', 15:46:28 INFO - 'tp6_facebook']}, 15:46:28 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:46:28 INFO - 'talos_options': ['--enable-stylo', 15:46:28 INFO - '--stylo-threads=1', 15:46:28 INFO - '--mitmproxy', 15:46:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:46:28 INFO - '--firstNonBlankPaint'], 15:46:28 INFO - 'tests': ['tp6_google', 15:46:28 INFO - 'tp6_youtube', 15:46:28 INFO - 'tp6_amazon', 15:46:28 INFO - 'tp6_facebook']}, 15:46:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:46:28 INFO - 'talos_options': ['--xperf_path', 15:46:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:28 INFO - 'tests': ['tp5n']}, 15:46:28 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 15:46:28 INFO - 'talos_options': ['--enable-stylo', 15:46:28 INFO - '--xperf_path', 15:46:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:46:28 INFO - 'tests': ['tp5n']}}} 15:46:28 INFO - Running post-action listener: _resource_record_post_action 15:46:28 INFO - [mozharness: 2017-09-05 22:46:28.912355Z] Finished populate-webroot step (success) 15:46:28 INFO - [mozharness: 2017-09-05 22:46:28.912448Z] Running create-virtualenv step. 15:46:28 INFO - Running pre-action listener: _resource_record_pre_action 15:46:28 INFO - Running main action method: create_virtualenv 15:46:28 INFO - Creating virtualenv /builds/slave/test/build/venv 15:46:28 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:46:28 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:46:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:46:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:46:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:46:29 INFO - Installing distribute.............................................................................................................................................................................................done. 15:46:29 INFO - Installing pip...............done. 15:46:29 INFO - Return code: 0 15:46:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:46:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:46:29 INFO - Reading from file tmpfile_stdout 15:46:29 INFO - Output received: 15:46:29 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:46:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:29 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:29 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:46:29 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:46:29 INFO - Using env: {'DISPLAY': ':0', 15:46:29 INFO - 'HOME': '/home/cltbld', 15:46:29 INFO - 'LANG': 'en_US.UTF-8', 15:46:29 INFO - 'LANGUAGE': 'en_US:en', 15:46:29 INFO - 'LOGNAME': 'cltbld', 15:46:29 INFO - 'MAIL': '/var/mail/cltbld', 15:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:29 INFO - 'MOZ_NO_REMOTE': '1', 15:46:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:29 INFO - 'NO_EM_RESTART': '1', 15:46:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:29 INFO - 'PWD': '/builds/slave/test', 15:46:29 INFO - 'SHELL': '/bin/bash', 15:46:29 INFO - 'SHLVL': '1', 15:46:29 INFO - 'TERM': 'linux', 15:46:29 INFO - 'TMOUT': '86400', 15:46:29 INFO - 'USER': 'cltbld', 15:46:29 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:29 INFO - '_': '/tools/buildbot/bin/python'} 15:46:31 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:46:31 INFO - Downloading/unpacking pip>=1.5 15:46:31 INFO - Running setup.py egg_info for package pip 15:46:31 INFO - warning: no files found matching 'pip/cacert.pem' 15:46:31 INFO - warning: no files found matching '*.html' under directory 'docs' 15:46:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:46:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:46:31 INFO - Installing collected packages: pip 15:46:31 INFO - Found existing installation: pip 1.1 15:46:31 INFO - Uninstalling pip: 15:46:31 INFO - Successfully uninstalled pip 15:46:31 INFO - Running setup.py install for pip 15:46:31 INFO - warning: no files found matching 'pip/cacert.pem' 15:46:31 INFO - warning: no files found matching '*.html' under directory 'docs' 15:46:31 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:46:31 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:46:31 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:46:31 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:46:31 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:46:31 INFO - Successfully installed pip 15:46:31 INFO - Cleaning up... 15:46:31 INFO - Return code: 0 15:46:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:46:31 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:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:31 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:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:31 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:31 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:46:31 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:46:31 INFO - Using env: {'DISPLAY': ':0', 15:46:31 INFO - 'HOME': '/home/cltbld', 15:46:31 INFO - 'LANG': 'en_US.UTF-8', 15:46:31 INFO - 'LANGUAGE': 'en_US:en', 15:46:31 INFO - 'LOGNAME': 'cltbld', 15:46:31 INFO - 'MAIL': '/var/mail/cltbld', 15:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:31 INFO - 'MOZ_NO_REMOTE': '1', 15:46:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:31 INFO - 'NO_EM_RESTART': '1', 15:46:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:31 INFO - 'PWD': '/builds/slave/test', 15:46:31 INFO - 'SHELL': '/bin/bash', 15:46:31 INFO - 'SHLVL': '1', 15:46:31 INFO - 'TERM': 'linux', 15:46:31 INFO - 'TMOUT': '86400', 15:46:31 INFO - 'USER': 'cltbld', 15:46:31 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:31 INFO - '_': '/tools/buildbot/bin/python'} 15:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:31 INFO - Downloading/unpacking psutil>=3.1.1 15:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:46:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:32 INFO - Installing collected packages: psutil 15:46:32 INFO - Running setup.py install for psutil 15:46:32 INFO - building 'psutil._psutil_linux' extension 15:46:32 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:46:32 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:46:32 INFO - building 'psutil._psutil_posix' extension 15:46:32 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:46:32 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:46:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:46:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:46:33 INFO - Successfully installed psutil 15:46:33 INFO - Cleaning up... 15:46:33 INFO - Return code: 0 15:46:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:46:33 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:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:33 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:46:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:33 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:33 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:46:33 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:46:33 INFO - Using env: {'DISPLAY': ':0', 15:46:33 INFO - 'HOME': '/home/cltbld', 15:46:33 INFO - 'LANG': 'en_US.UTF-8', 15:46:33 INFO - 'LANGUAGE': 'en_US:en', 15:46:33 INFO - 'LOGNAME': 'cltbld', 15:46:33 INFO - 'MAIL': '/var/mail/cltbld', 15:46:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:33 INFO - 'MOZ_NO_REMOTE': '1', 15:46:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:33 INFO - 'NO_EM_RESTART': '1', 15:46:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:33 INFO - 'PWD': '/builds/slave/test', 15:46:33 INFO - 'SHELL': '/bin/bash', 15:46:33 INFO - 'SHLVL': '1', 15:46:33 INFO - 'TERM': 'linux', 15:46:33 INFO - 'TMOUT': '86400', 15:46:33 INFO - 'USER': 'cltbld', 15:46:33 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:33 INFO - '_': '/tools/buildbot/bin/python'} 15:46:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:46:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:46:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:46:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:46:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:46:34 INFO - Installing collected packages: mozsystemmonitor 15:46:34 INFO - Running setup.py install for mozsystemmonitor 15:46:34 INFO - Successfully installed mozsystemmonitor 15:46:34 INFO - Cleaning up... 15:46:34 INFO - Return code: 0 15:46:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:34 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:34 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:46:34 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:46:34 INFO - Using env: {'DISPLAY': ':0', 15:46:34 INFO - 'HOME': '/home/cltbld', 15:46:34 INFO - 'LANG': 'en_US.UTF-8', 15:46:34 INFO - 'LANGUAGE': 'en_US:en', 15:46:34 INFO - 'LOGNAME': 'cltbld', 15:46:34 INFO - 'MAIL': '/var/mail/cltbld', 15:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:34 INFO - 'MOZ_NO_REMOTE': '1', 15:46:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:34 INFO - 'NO_EM_RESTART': '1', 15:46:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:34 INFO - 'PWD': '/builds/slave/test', 15:46:34 INFO - 'SHELL': '/bin/bash', 15:46:34 INFO - 'SHLVL': '1', 15:46:34 INFO - 'TERM': 'linux', 15:46:34 INFO - 'TMOUT': '86400', 15:46:34 INFO - 'USER': 'cltbld', 15:46:34 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:34 INFO - '_': '/tools/buildbot/bin/python'} 15:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:34 INFO - Downloading/unpacking jsonschema==2.5.1 15:46:34 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:46:34 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:46:34 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:46:34 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:46:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:46:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:46:35 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:46:35 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:46:35 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:46:35 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:46:36 INFO - Downloading functools32-3.2.3-2.tar.gz 15:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:46:36 INFO - warning: no files found matching '*.txt' 15:46:36 INFO - no previously-included directories found matching 'build' 15:46:36 INFO - no previously-included directories found matching 'dist' 15:46:36 INFO - no previously-included directories found matching '.git*' 15:46:36 INFO - Installing collected packages: jsonschema, functools32 15:46:36 INFO - Running setup.py install for functools32 15:46:36 INFO - warning: no files found matching '*.txt' 15:46:36 INFO - no previously-included directories found matching 'build' 15:46:36 INFO - no previously-included directories found matching 'dist' 15:46:36 INFO - no previously-included directories found matching '.git*' 15:46:36 INFO - Successfully installed jsonschema functools32 15:46:36 INFO - Cleaning up... 15:46:36 INFO - Return code: 0 15:46:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:46:36 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:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:36 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:46:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:36 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:36 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:46:36 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:46:36 INFO - Using env: {'DISPLAY': ':0', 15:46:36 INFO - 'HOME': '/home/cltbld', 15:46:36 INFO - 'LANG': 'en_US.UTF-8', 15:46:36 INFO - 'LANGUAGE': 'en_US:en', 15:46:36 INFO - 'LOGNAME': 'cltbld', 15:46:36 INFO - 'MAIL': '/var/mail/cltbld', 15:46:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:36 INFO - 'MOZ_NO_REMOTE': '1', 15:46:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:36 INFO - 'NO_EM_RESTART': '1', 15:46:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:36 INFO - 'PWD': '/builds/slave/test', 15:46:36 INFO - 'SHELL': '/bin/bash', 15:46:36 INFO - 'SHLVL': '1', 15:46:36 INFO - 'TERM': 'linux', 15:46:36 INFO - 'TMOUT': '86400', 15:46:36 INFO - 'USER': 'cltbld', 15:46:36 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:36 INFO - '_': '/tools/buildbot/bin/python'} 15:46:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:46:36 INFO - Cleaning up... 15:46:36 INFO - Return code: 0 15:46:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:46:36 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:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:36 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:46:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:36 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:36 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:46:36 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:46:36 INFO - Using env: {'DISPLAY': ':0', 15:46:36 INFO - 'HOME': '/home/cltbld', 15:46:36 INFO - 'LANG': 'en_US.UTF-8', 15:46:36 INFO - 'LANGUAGE': 'en_US:en', 15:46:36 INFO - 'LOGNAME': 'cltbld', 15:46:36 INFO - 'MAIL': '/var/mail/cltbld', 15:46:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:36 INFO - 'MOZ_NO_REMOTE': '1', 15:46:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:36 INFO - 'NO_EM_RESTART': '1', 15:46:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:36 INFO - 'PWD': '/builds/slave/test', 15:46:36 INFO - 'SHELL': '/bin/bash', 15:46:36 INFO - 'SHLVL': '1', 15:46:36 INFO - 'TERM': 'linux', 15:46:36 INFO - 'TMOUT': '86400', 15:46:36 INFO - 'USER': 'cltbld', 15:46:36 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:36 INFO - '_': '/tools/buildbot/bin/python'} 15:46:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:36 INFO - Downloading/unpacking blobuploader==1.2.4 15:46:36 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:46:36 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:46:36 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:46:36 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:46:37 INFO - Downloading blobuploader-1.2.4.tar.gz 15:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:46:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:46:37 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:46:37 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:46:37 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:46:37 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:46:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:46:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:46:38 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:46:38 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:46:38 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:46:38 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:46:38 INFO - Downloading docopt-0.6.1.tar.gz 15:46:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:46:38 INFO - Installing collected packages: blobuploader, requests, docopt 15:46:38 INFO - Running setup.py install for blobuploader 15:46:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:46:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:46:38 INFO - Running setup.py install for requests 15:46:38 INFO - Running setup.py install for docopt 15:46:38 INFO - Successfully installed blobuploader requests docopt 15:46:38 INFO - Cleaning up... 15:46:38 INFO - Return code: 0 15:46:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:38 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:38 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:46:38 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:46:38 INFO - Using env: {'DISPLAY': ':0', 15:46:38 INFO - 'HOME': '/home/cltbld', 15:46:38 INFO - 'LANG': 'en_US.UTF-8', 15:46:38 INFO - 'LANGUAGE': 'en_US:en', 15:46:38 INFO - 'LOGNAME': 'cltbld', 15:46:39 INFO - 'MAIL': '/var/mail/cltbld', 15:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:39 INFO - 'MOZ_NO_REMOTE': '1', 15:46:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:39 INFO - 'NO_EM_RESTART': '1', 15:46:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:39 INFO - 'PWD': '/builds/slave/test', 15:46:39 INFO - 'SHELL': '/bin/bash', 15:46:39 INFO - 'SHLVL': '1', 15:46:39 INFO - 'TERM': 'linux', 15:46:39 INFO - 'TMOUT': '86400', 15:46:39 INFO - 'USER': 'cltbld', 15:46:39 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:39 INFO - '_': '/tools/buildbot/bin/python'} 15:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:46:39 INFO - Running setup.py (path:/tmp/pip-AEHcEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:46:39 INFO - Running setup.py (path:/tmp/pip-nCaJ1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:46:39 INFO - Running setup.py (path:/tmp/pip-WrhWp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:46:39 INFO - Running setup.py (path:/tmp/pip-BdBY8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:46:39 INFO - Running setup.py (path:/tmp/pip-ABn9T0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:46:39 INFO - Running setup.py (path:/tmp/pip-oLpksm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:46:39 INFO - Running setup.py (path:/tmp/pip-IqxgA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:46:39 INFO - Running setup.py (path:/tmp/pip-JGLlII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:46:39 INFO - Running setup.py (path:/tmp/pip-LcRnAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:46:39 INFO - Running setup.py (path:/tmp/pip-ZHsW3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:46:39 INFO - Running setup.py (path:/tmp/pip-BSgVTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:46:39 INFO - Running setup.py (path:/tmp/pip-n09Nws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:46:40 INFO - Running setup.py (path:/tmp/pip-UNrI5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:46:40 INFO - Running setup.py (path:/tmp/pip-DyxrOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:40 INFO - Running setup.py (path:/tmp/pip-Zj_hKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:46:40 INFO - Running setup.py (path:/tmp/pip-wVLON5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:46:40 INFO - Running setup.py (path:/tmp/pip-ZHfCWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:46:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:46:40 INFO - Running setup.py install for manifestparser 15:46:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:46:40 INFO - Running setup.py install for mozcrash 15:46:40 INFO - Running setup.py install for mozdebug 15:46:40 INFO - Running setup.py install for mozdevice 15:46:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:46:40 INFO - Running setup.py install for mozfile 15:46:40 INFO - Running setup.py install for mozhttpd 15:46:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:46:40 INFO - Running setup.py install for mozinfo 15:46:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Running setup.py install for mozInstall 15:46:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Running setup.py install for mozleak 15:46:41 INFO - Running setup.py install for mozlog 15:46:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Running setup.py install for moznetwork 15:46:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Running setup.py install for mozprocess 15:46:41 INFO - Running setup.py install for mozprofile 15:46:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Running setup.py install for mozrunner 15:46:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:46:41 INFO - Running setup.py install for mozscreenshot 15:46:41 INFO - Running setup.py install for moztest 15:46:42 INFO - Running setup.py install for mozversion 15:46:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:46:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:46:42 INFO - Cleaning up... 15:46:42 INFO - Return code: 0 15:46:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:42 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:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:42 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:46:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:42 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:42 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:46:42 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:46:42 INFO - Using env: {'DISPLAY': ':0', 15:46:42 INFO - 'HOME': '/home/cltbld', 15:46:42 INFO - 'LANG': 'en_US.UTF-8', 15:46:42 INFO - 'LANGUAGE': 'en_US:en', 15:46:42 INFO - 'LOGNAME': 'cltbld', 15:46:42 INFO - 'MAIL': '/var/mail/cltbld', 15:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:42 INFO - 'MOZ_NO_REMOTE': '1', 15:46:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:42 INFO - 'NO_EM_RESTART': '1', 15:46:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:42 INFO - 'PWD': '/builds/slave/test', 15:46:42 INFO - 'SHELL': '/bin/bash', 15:46:42 INFO - 'SHLVL': '1', 15:46:42 INFO - 'TERM': 'linux', 15:46:42 INFO - 'TMOUT': '86400', 15:46:42 INFO - 'USER': 'cltbld', 15:46:42 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:42 INFO - '_': '/tools/buildbot/bin/python'} 15:46:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:46:42 INFO - Running setup.py (path:/tmp/pip-VsnfOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:46:42 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:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:46:42 INFO - Running setup.py (path:/tmp/pip-0Peor_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:46:42 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:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:46:42 INFO - Running setup.py (path:/tmp/pip-KEh6Rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:46:42 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:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:46:42 INFO - Running setup.py (path:/tmp/pip-qcAK6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:46:42 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:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:46:42 INFO - Running setup.py (path:/tmp/pip-XrLAzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:46:42 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:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:46:42 INFO - Running setup.py (path:/tmp/pip-N4wKng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:46:42 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:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:46:42 INFO - Running setup.py (path:/tmp/pip-wXLW0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:46:42 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:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:46:42 INFO - Running setup.py (path:/tmp/pip-VGRxeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:46:42 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:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:46:42 INFO - Running setup.py (path:/tmp/pip-wZ2FSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:46:42 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:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:46:43 INFO - Running setup.py (path:/tmp/pip-2M99GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:46:43 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:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:46:43 INFO - Running setup.py (path:/tmp/pip-7S_Mqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:46:43 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:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:46:43 INFO - Running setup.py (path:/tmp/pip-eFPfU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:46:43 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:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:46:43 INFO - Running setup.py (path:/tmp/pip-q31aZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:46:43 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:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:46:43 INFO - Running setup.py (path:/tmp/pip-0jsq1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:46:43 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:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:43 INFO - Running setup.py (path:/tmp/pip-UiIIbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:46:43 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:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:46:43 INFO - Running setup.py (path:/tmp/pip-EGNHdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:46:43 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:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:46:43 INFO - Running setup.py (path:/tmp/pip-5DaNqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:46:43 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:46:43 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:46:43 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:46:43 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:46:43 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:46:43 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:46:43 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:46:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:46:43 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:46:43 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:46:43 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:46:43 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:46:44 INFO - Downloading blessings-1.6.tar.gz 15:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:46:44 INFO - Installing collected packages: blessings 15:46:44 INFO - Running setup.py install for blessings 15:46:44 INFO - Successfully installed blessings 15:46:44 INFO - Cleaning up... 15:46:44 INFO - Return code: 0 15:46:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:46:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:46:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:46:45 INFO - Reading from file tmpfile_stdout 15:46:45 INFO - Current package versions: 15:46:45 INFO - argparse == 1.2.1 15:46:45 INFO - blessings == 1.6 15:46:45 INFO - blobuploader == 1.2.4 15:46:45 INFO - docopt == 0.6.1 15:46:45 INFO - functools32 == 3.2.3-2 15:46:45 INFO - jsonschema == 2.5.1 15:46:45 INFO - manifestparser == 1.1 15:46:45 INFO - mozInstall == 1.13 15:46:45 INFO - mozcrash == 1.0 15:46:45 INFO - mozdebug == 0.1 15:46:45 INFO - mozdevice == 0.51 15:46:45 INFO - mozfile == 1.2 15:46:45 INFO - mozhttpd == 0.7 15:46:45 INFO - mozinfo == 0.10 15:46:45 INFO - mozleak == 0.1 15:46:45 INFO - mozlog == 3.5 15:46:45 INFO - moznetwork == 0.27 15:46:45 INFO - mozprocess == 0.25 15:46:45 INFO - mozprofile == 0.28 15:46:45 INFO - mozrunner == 6.13 15:46:45 INFO - mozscreenshot == 0.1 15:46:45 INFO - mozsystemmonitor == 0.3 15:46:45 INFO - moztest == 0.8 15:46:45 INFO - mozversion == 1.4 15:46:45 INFO - psutil == 3.1.1 15:46:45 INFO - requests == 1.2.3 15:46:45 INFO - wsgiref == 0.1.2 15:46:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:45 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:45 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:46:45 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:46:45 INFO - Using env: {'DISPLAY': ':0', 15:46:45 INFO - 'HOME': '/home/cltbld', 15:46:45 INFO - 'LANG': 'en_US.UTF-8', 15:46:45 INFO - 'LANGUAGE': 'en_US:en', 15:46:45 INFO - 'LOGNAME': 'cltbld', 15:46:45 INFO - 'MAIL': '/var/mail/cltbld', 15:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:45 INFO - 'MOZ_NO_REMOTE': '1', 15:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:45 INFO - 'NO_EM_RESTART': '1', 15:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:45 INFO - 'PWD': '/builds/slave/test', 15:46:45 INFO - 'SHELL': '/bin/bash', 15:46:45 INFO - 'SHLVL': '1', 15:46:45 INFO - 'TERM': 'linux', 15:46:45 INFO - 'TMOUT': '86400', 15:46:45 INFO - 'USER': 'cltbld', 15:46:45 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:45 INFO - '_': '/tools/buildbot/bin/python'} 15:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:45 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:46:45 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:46:45 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:46:45 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:46:45 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:46:45 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:46:45 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:46:45 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:46:45 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:46:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:46:45 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:46:45 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:46:45 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:46:45 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:46:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:46:46 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:46:46 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:46:46 INFO - Installing collected packages: simplejson 15:46:46 INFO - Running setup.py install for simplejson 15:46:46 INFO - building 'simplejson._speedups' extension 15:46:46 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:46:47 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:46:47 INFO - Successfully installed simplejson 15:46:47 INFO - Cleaning up... 15:46:47 INFO - Return code: 0 15:46:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:46:47 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:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:46:47 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:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:46:47 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 0x1b1f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ce8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e8ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, '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': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', '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:46:47 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:46:47 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:46:47 INFO - Using env: {'DISPLAY': ':0', 15:46:47 INFO - 'HOME': '/home/cltbld', 15:46:47 INFO - 'LANG': 'en_US.UTF-8', 15:46:47 INFO - 'LANGUAGE': 'en_US:en', 15:46:47 INFO - 'LOGNAME': 'cltbld', 15:46:47 INFO - 'MAIL': '/var/mail/cltbld', 15:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:46:47 INFO - 'MOZ_NO_REMOTE': '1', 15:46:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:46:47 INFO - 'NO_EM_RESTART': '1', 15:46:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:46:47 INFO - 'PWD': '/builds/slave/test', 15:46:47 INFO - 'SHELL': '/bin/bash', 15:46:47 INFO - 'SHLVL': '1', 15:46:47 INFO - 'TERM': 'linux', 15:46:47 INFO - 'TMOUT': '86400', 15:46:47 INFO - 'USER': 'cltbld', 15:46:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:46:47 INFO - '_': '/tools/buildbot/bin/python'} 15:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:46:47 INFO - Cleaning up... 15:46:47 INFO - Return code: 0 15:46:47 INFO - Running post-action listener: _resource_record_post_action 15:46:47 INFO - Running post-action listener: _start_resource_monitoring 15:46:47 INFO - Starting resource monitoring. 15:46:47 INFO - [mozharness: 2017-09-05 22:46:47.835341Z] Finished create-virtualenv step (success) 15:46:47 INFO - [mozharness: 2017-09-05 22:46:47.835808Z] Running install step. 15:46:47 INFO - Running pre-action listener: _resource_record_pre_action 15:46:47 INFO - Running main action method: install 15:46:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:46:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:46:48 INFO - Reading from file tmpfile_stdout 15:46:48 INFO - Detecting whether we're running mozinstall >=1.0... 15:46:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:46:48 INFO - Reading from file tmpfile_stdout 15:46:48 INFO - Output received: 15:46:48 INFO - Usage: mozinstall [options] installer 15:46:48 INFO - Options: 15:46:48 INFO - -h, --help show this help message and exit 15:46:48 INFO - -d DEST, --destination=DEST 15:46:48 INFO - Directory to install application into. [default: 15:46:48 INFO - "/builds/slave/test"] 15:46:48 INFO - --app=APP Application being installed. [default: firefox] 15:46:48 INFO - mkdir: /builds/slave/test/build/application 15:46:48 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:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:47:04 INFO - Reading from file tmpfile_stdout 15:47:04 INFO - Output received: 15:47:04 INFO - /builds/slave/test/build/application/firefox/firefox 15:47:04 INFO - Running post-action listener: _resource_record_post_action 15:47:04 INFO - [mozharness: 2017-09-05 22:47:04.789324Z] Finished install step (success) 15:47:04 INFO - [mozharness: 2017-09-05 22:47:04.789469Z] Running setup-mitmproxy step. 15:47:04 INFO - Running pre-action listener: _resource_record_pre_action 15:47:04 INFO - Running main action method: setup_mitmproxy 15:47:04 INFO - Skipping: mitmproxy is not required 15:47:04 INFO - Running post-action listener: _resource_record_post_action 15:47:04 INFO - [mozharness: 2017-09-05 22:47:04.789998Z] Finished setup-mitmproxy step (success) 15:47:04 INFO - [mozharness: 2017-09-05 22:47:04.790124Z] Running run-tests step. 15:47:04 INFO - Running pre-action listener: _resource_record_pre_action 15:47:04 INFO - Running pre-action listener: _set_gcov_prefix 15:47:04 INFO - Running main action method: run_tests 15:47:04 WARNING - Try message not found. 15:47:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:47:04 INFO - Python 2.7.3 15:47:04 INFO - Return code: 0 15:47:04 INFO - grabbing minidump binary from tooltool 15:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:04 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 0x1e8f090>, '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 0x1e8fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e89f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:47:04 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:47:04 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:47:04 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:47:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:47:04 INFO - Return code: 0 15:47:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:47:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:47:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:47:04 INFO - ENV: RUST_BACKTRACE is now full 15:47:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:47:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:47:04 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-017', '--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:47:04 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-017 --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:47:04 INFO - Using env: {'DISPLAY': ':0', 15:47:04 INFO - 'HOME': '/home/cltbld', 15:47:04 INFO - 'LANG': 'en_US.UTF-8', 15:47:04 INFO - 'LANGUAGE': 'en_US:en', 15:47:04 INFO - 'LOGNAME': 'cltbld', 15:47:04 INFO - 'MAIL': '/var/mail/cltbld', 15:47:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:47:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:04 INFO - 'MOZ_NO_REMOTE': '1', 15:47:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:47:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:04 INFO - 'NO_EM_RESTART': '1', 15:47:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:04 INFO - 'PWD': '/builds/slave/test', 15:47:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:47:04 INFO - 'RUST_BACKTRACE': 'full', 15:47:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:47:04 INFO - 'SHELL': '/bin/bash', 15:47:04 INFO - 'SHLVL': '1', 15:47:04 INFO - 'STYLO_FORCE_ENABLED': '1', 15:47:04 INFO - 'TERM': 'linux', 15:47:04 INFO - 'TMOUT': '86400', 15:47:04 INFO - 'USER': 'cltbld', 15:47:04 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:04 INFO - '_': '/tools/buildbot/bin/python'} 15:47:04 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-017', '--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:47:05 INFO - mozversion application_buildid: 20170905215433 15:47:05 INFO - mozversion application_changeset: 978d2539a8d1a49e9f9705204f3918772b337547 15:47:05 INFO - mozversion application_display_name: Nightly 15:47:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:47:05 INFO - mozversion application_name: Firefox 15:47:05 INFO - mozversion application_remotingname: firefox 15:47:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:47:05 INFO - mozversion application_vendor: Mozilla 15:47:05 INFO - mozversion application_version: 57.0a1 15:47:05 INFO - mozversion platform_buildid: 20170905215433 15:47:05 INFO - mozversion platform_changeset: 978d2539a8d1a49e9f9705204f3918772b337547 15:47:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:47:05 INFO - mozversion platform_version: 57.0a1 15:47:05 INFO - using testdate: 1504651625 15:47:05 INFO - actual date: 1504651625 15:47:05 INFO - starting webserver on 'localhost:54880' 15:47:05 INFO - SUITE-START | Running 2 tests 15:47:05 INFO - TEST-START | dromaeo_css 15:47:05 INFO - Initialising browser for dromaeo_css test... 15:47:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54880/getInfo.html -profile /tmp/tmpN8Zqed/profile 15:47:05 INFO - TEST-INFO | started process 994 (/builds/slave/test/build/application/firefox/firefox http://localhost:54880/getInfo.html) 15:47:10 INFO - TEST-INFO | 994: exit 0 15:47:11 INFO - Browser initialized. 15:47:11 INFO - Running cycle 1/1 for dromaeo_css test... 15:47:11 INFO - Using env: {'DISPLAY': ':0', 15:47:11 INFO - 'HOME': '/home/cltbld', 15:47:11 INFO - 'JSGC_DISABLE_POISONING': '1', 15:47:11 INFO - 'LANG': 'en_US.UTF-8', 15:47:11 INFO - 'LANGUAGE': 'en_US:en', 15:47:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:47:11 INFO - 'LOGNAME': 'cltbld', 15:47:11 INFO - 'MAIL': '/var/mail/cltbld', 15:47:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:47:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:47:11 INFO - 'MOZ_CRASHREPORTER': '1', 15:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:47:11 INFO - 'MOZ_NO_REMOTE': '1', 15:47:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:47:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:11 INFO - 'NO_EM_RESTART': '1', 15:47:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:11 INFO - 'PWD': '/builds/slave/test', 15:47:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:47:11 INFO - 'RUST_BACKTRACE': 'full', 15:47:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:47:11 INFO - 'SHELL': '/bin/bash', 15:47:11 INFO - 'SHLVL': '1', 15:47:11 INFO - 'STYLO_FORCE_ENABLED': '1', 15:47:11 INFO - 'TERM': 'linux', 15:47:11 INFO - 'TMOUT': '86400', 15:47:11 INFO - 'USER': 'cltbld', 15:47:11 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 15:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:11 INFO - '_': '/tools/buildbot/bin/python'} 15:47:11 INFO - TEST-INFO | started process 1203 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpN8Zqed/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:47:12 INFO - PID 1203 | 15:47:12 INFO - PID 1203 | (/builds/slave/test/build/application/firefox/firefox:1324): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:47:12 INFO - PID 1203 | 15:47:22 INFO - PID 1203 | RSS: Main: 167366656 15:47:22 INFO - PID 1203 | 15:50:19 INFO - PID 1203 | Cycle 1(1): loaded http://localhost:54880/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54880/tests/dromaeo/cssquery-ext.html) 15:50:20 INFO - PID 1203 | RSS: Main: 171884544 15:50:20 INFO - PID 1203 | 15:53:22 INFO - PID 1203 | Cycle 1(1): loaded http://localhost:54880/tests/dromaeo/cssquery-ext.html (next: http://localhost:54880/tests/dromaeo/cssquery-jquery.html) 15:53:23 INFO - PID 1203 | RSS: Main: 171286528 15:53:23 INFO - PID 1203 | 15:56:25 INFO - PID 1203 | Cycle 1(1): loaded http://localhost:54880/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54880/tests/dromaeo/cssquery-mootools.html) 15:56:25 INFO - PID 1203 | RSS: Main: 173793280 15:56:25 INFO - PID 1203 | 15:59:27 INFO - PID 1203 | Cycle 1(1): loaded http://localhost:54880/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54880/tests/dromaeo/cssquery-prototype.html) 15:59:28 INFO - PID 1203 | RSS: Main: 172060672 15:59:28 INFO - PID 1203 | 16:02:31 INFO - PID 1203 | Cycle 1(1): loaded http://localhost:54880/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54880/tests/dromaeo/cssquery-yui.html) 16:02:32 INFO - PID 1203 | RSS: Main: 175448064 16:02:32 INFO - PID 1203 | 16:05:33 INFO - PID 1203 | Cycle 1(1): loaded http://localhost:54880/tests/dromaeo/cssquery-yui.html (next: http://localhost:54880/tests/dromaeo/cssquery-dojo.html) 16:05:34 INFO - PID 1203 | RSS: Main: 175894528 16:05:34 INFO - PID 1203 | 16:05:34 INFO - PID 1203 | __start_tp_report 16:05:34 INFO - PID 1203 | _x_x_mozilla_page_load 16:05:34 INFO - PID 1203 | _x_x_mozilla_page_load_details 16:05:34 INFO - PID 1203 | |i|pagename|runs| 16:05:34 INFO - PID 1203 | |0;dojo.html;3221.742260619151;3269.7547683923713;3324.9667503324977;3336.532885396044;3353.8323083845803;6308.369163083689;6328.683565821702;6388.041793730943;6473.773417930373;6521.445677117451;6468.256150542689;6516.055172000057;6537.575057621251;6538.215414150297;6583.012548117785;5716.685582292972;5831.416858314155;5913.822585322437;5978.192943952576;5986.491148252404;5818.156367326735;5849.912251316234;5850.824475265749;5992.34084250732;6001.5198784097165;5910.911336329958;5967.850803729898;5969.074793407029;5986.2517185351835;5993.61041532299;5872.853178670524;5891.410858914117;5973.342932277446;5980.192673989;5996.670183139926;5709.286339207599;5801.0428279333855;5823.475887170132;5934.13954976526;5956.404359564052;6359.300476947532;6387.2654644715985;6406.583572067847;6438.259600145996;6445.774397896098;6850.554713943628;6856.040154378391;6931.064306318633;6943.583384996917;6965.5124141310125;6916.827079323007;6919.619420931846;6944.895826562605;7026.543274687129;7047.506674532785;6254.624722516618;6331.841703957438;6400.871982560369;6494.253160886464;6496.642684652342;7076.823079423056;7083.574985500886;7084.574925504487;7093.290670932969;7105.32499412555;6659.433948114464;6681.899771503431;6770.627615481098;6774.966125169342;6775.966120169466;6763.93236067633;6780.559263647946;6844.623545704934;6883;6895.5173137879865;6656.467482601381;6727.764528241488;6742.224644165984;6781.152355955505;6837.555558888656;6730.697118629673;6749.493787965922;6793.45652347811;6803.591784492946;6812.386885180357;5391.083515802323;5402.540784033401;5406.972965135149;5407.621466497386;5416.51251387377;4718.528147185254;4726.456457507362;4739.194336962724;4739.3601863748645;4743.573078422958;5904.409559044061;5909.113632955005;5910.527157827363;5932.377100404396;5973.04431291;6921.688524016431;6938;7003.054587630707;7023;7032;5796.449337312949;5938.673372964442;5962.22491076166;6044.972354699712;6046.546509011754;5856.824295271149;5904.321003084614;5920.8815823684145;5928.970355148197;5930.644161350333;6705.6982435790505;6718.865622687519;6728.697208625624;6738.932610673831;6748.831279218059;6891.173059232924;6985.545939513915;6988.231294557696;6995.475339349569;7004.824879378159;6520.315366886598;6604.3725846044545;6615.239247486601;6615.536912416084;6617.867642647217;6695.966520167368;6723.294054124247;6727.461803055646;6727.663616819237;6730.125083739083;6568.310327415553;6765.864682706417;6769.052332673528;6769.153855768029;6925.826854328682;6715.865682686416;6721.1598550181225;6790.151231096113;6808.012838138343;6813.080234168522;6473.546851720334;6566.770163044462;6610.603363798187;6707.765228216989;6802.115724955724;6499.805005849832;6555.2789193187755;6583.506237032338;6585.1439312889015;6585.670716464062;6588.901166482602;6651.235107962646;6687.6321802301345;6692.230393504809;6725.327467253235;6392.4246817786625;6499.902501462388;6530.151080359523;6601.108850305147;6651.534392592472 16:05:34 INFO - PID 1203 | |1;ext.html;786.3355464632388;850.3749743938205;880.6213328268842;1131.7679875625493;1159.1364433497047;30366.54450183246;39556.20887582246;40966.79516602413;40980.590194098084;41043.179136417326;40723.79638101805;40749.388759168636;40792.796036019776;41027.38458923119;41097.58902410975;7539.2460753924715;7696.961515192416;7702.730404435846;7706.884396734025;7725.227477252286;7599.35405490534;7685.6157192140445;7705.383569314469;7713.112992005907;7713.190115037924;7255.600941948191;7828.686852525892;7832.56920869352;7833.373330133604;7837.725679601187;7681.3086822186;7698.3071523562585;7703.653335599912;7729.149793522708;7773.222677732209;7499.6250187490405;7584.582847943361;7586.696532138708;7632.694692212305;7634.083909930789;36572.63427365719;36877.81561092204;36890.07774805625;37134;37274;3527.4532447470683;3561.0919215599856;3570.946435803465;3571.982140089283;3572.142685755426;30349.393012139633;30474.54288185679;30520.69479305223;30527.38945221083;30593.38813223723;4685.390899183119;4705.952940470585;4727.22000869856;4735.9817639207395;4741.786619602126;188381;190258;190282;190901.04549477302;191149;46824.7658761704;47135;47213.52786472091;47289.52710472851;47331;49421;49552;49568.504314956386;49584.256236156485;49610.255846162334;15352.385904563916;15570.221488925508;15584.298706558233;15602;15622.375104995901;12547.372631368395;12554.874451255371;12591.118621696392;12603.117781755189;12609.180403273942;12319.384030798425;12572.559960401342;14136.434542618386;14147.787783183261;14204.928975355057;5596.916046259309;5705.22979397776;5786.942130578725;5788.102844059177;5928.229330187048;1012.1447376966452;1022.2895087913977;1043.3687618990402;1048.5596049659162;1065.8507808906759;22862.31413057611;23135.190268340528;23181.53636927252;23328.533429331317;23503.294901152993;1128.114430172311;1138.362516990488;1152.0714364018656;1153.1927650644582;1161.5063597970998;12275.631731047904;12455.501779928702;12482.625521234377;12526;12550.309732964775;232403;233719;233861;236292;236750;53355;53761.19358209552;53766.19350709742;53918.46081539213;54006.45993540014;1438.3311760031636;1452.9740873072742;1469.5077149155152;1485.6731519065788;1497.4160077569538;23532.411689707893;23536.940837662005;23569.41076473102;23630.52738945177;23692.289231323088;37442.625573745005;37471.43792843055;37501.2499749998;37532.2493550122;37557.62442375541;205288;205307;205894;206798;207879;32042.35915281728;33371.16572085717;33499.49750753692;33510.66489335173;33519;37580.43629345507;38645.61354386533;41025.79487102546;43215;43644.56355436405;31615;31896;32157.678423215468;32424.83787581047;32599.674003259664;31554;31595.368092638477;31616.841915791196;31873.521897172024;31875;35212.11969700778;35319.470207947415;35516;35563.46654800232;35567.82216088903;30535.694643053284;30789.692103078683;30790.384192316476;31021.534676979412;31447.371052579278;23176.3047108587;23512;23653.645195322428;23736.406265588732;23794.40513987095 16:05:34 INFO - PID 1203 | |2;jquery.html;556.69381839988;563.335264388022;567.7615401531357;581.450529249859;589.5048159545981;21977.780222197773;23032.65451018236;23038.078476860926;23050.30849074526;23067.077316907304;23658.5268294634;24799.38001549962;25811.483770324583;26018.869905650445;26155.82298796554;6603.3396660334065;6738.12404387429;6745.898811517831;6747.696353664097;6750.122484077063;6090.60411073281;6353.174087368636;6633.03820945965;6667.666616669171;6668;6639.634820084893;6644.966775166142;6648.9667551662415;6653.035309880064;6653.634050127241;6656.334366563329;6656.367645073735;6658.30087840777;6658.533902626818;6660.966695166493;6135.478484328837;6137.969310153465;6147.631142131441;6160.4147605977805;6170.321264660884;28039.29901752452;28509.85745071282;28701.712982870115;28724.425511489862;29219;2032.8475364347732;2065.452655046414;2066.349100033485;2069.265410779167;2072.751269847628;19285.614287714307;19840.900795496076;19964.401067967985;20007.29974450902;20046.599068018702;2369.9413567841643;2387.403149212697;2396.317049640844;2399.820013498977;2442.8412153209983;135613.3219333907;139081;139392;139554.30222848922;139808;13817.516386926409;14433.783493247609;14579.781303280459;14783.260836958108;14958.401663933539;21742.89128554379;23094.191703290304;23102.53794924092;23120.53758924812;23185;10069.446180460145;10078.244131690006;10102.69691909244;10340.896591034143;10379;9264.25885929124;9342.626294948126;9391.953040234892;9414.717558473258;9425.90574094264;9100.81798364029;9113.589888455035;9191.540422978824;9352.859707104399;9545.47499887499;2597.584176807492;2622.1871219921886;2644.1538707613763;2674.5453272943255;2739.2330147558714;957.5355952363099;961.778790878088;966.5360626898987;981.3572110267786;999.3604093380243;19453.51366215856;19748.802511974984;19968.20127194925;20007.699884501453;23821.642675359883;989.9307048506679;1050.1546255264432;1055.2191378379944;1061.0609610494769;1061.9681409557725;10608.310459820086;11415.942920285346;13445.663858403617;13869.861301387058;14430.711385772225;491225;515893;516788;517183;529129;112716;113467;116768;117985;118777.40611296888;1253.3607859991287;1256.761215369077;1280.5134049061296;1280.6670265730977;1283.6983308922395;21268.7873121273;21385.786142139;21480.355589332346;21509.354719358445;21549.569008619426;48095.75952120217;52093;57293;57739.711301444906;58774.11838822506;64937.35062649313;65036.34963650303;65696.67151664403;65947;66197.66901165464;14260.144391336553;14528.927355363156;14671.413143474141;14674.339654715777;14696.41214351414;23156.76843231546;23292.6506102412;23347.299581012598;23348;23555.646665300377;18107.275708971494;18151.09244537794;18294;18418.171182297097;18427.355042573447;16547.83452165511;16875.24061417264;17096.14519274056;17123.914380428017;17502.73745893838;18573.81426185721;18845.528861778566;19013.90493047526;19094.90452547784;19170.808291916903;14313.355898984362;14360.640983975483;14452.566423007327;14458.493952711604;14531.491397801026;18060.729089063938;18168;18199.181036853108;18510.722339164655;18657.9738114405 16:05:34 INFO - PID 1203 | |3;mootools.html;1363.8840698540625;1394.7210557888425;1425.2873563218384;1425.6293363725429;1429.756941319975;14703.779443308333;15233.009854359472;15900.52298431049;15910;15910.124943128138;16230.594235144128;16532;16533.338666453357;16543.25555350009;16546.172691365413;2701.5677491601346;2763.226296636935;2764.530029894913;2764.8064635475525;2764.9308767280777;2635.0908936416904;2666.1068542038443;2764.543850264704;2827.8586070696488;2831.7168283171723;2745.7117002714726;2777.319556708599;2777.847218402987;2783.471140483312;2785.4289870576586;2610.8041896857717;2627.8686065696734;2679.705232424432;2681.316264352597;2686.7313268673074;2594.883230254643;2621.279148234232;2623.6851577810594;2628.47430513898;2634.7233540478164;15118.244087795567;15488.612784680361;15491.922540387339;15496.070235785775;15500.767488487681;7899.644515996794;8089.988751406074;8131.14622964592;8179.672813087469;8183.549904755236;5051.368578927634;5188.377394712621;5317.707526086064;5348.973255133699;5476.452354764531;1880.8213219744102;1906.961860762777;1932.9710054349196;1961.1763059515038;1981.6135853508572;25053.62419563708;25285;25312;25318;26652.466950660877;6627.403533681992;6734.865302693918;6818.659067046628;6853.7943861684225;6873.48448866327;17671.823281767018;17699.91150044267;17708.734368984475;17723.113844307733;17726.91136544309;7853.41099417546;7912.960435197902;7948.966634337499;7950.522968621901;7954.204579542115;7263.92736072643;7303.89044164338;7352.19125896151;7364.263573642593;7411.407087432993;5043.9243411348125;5224.138017227191;5229.712365819841;5258.053550360972;5308.150695888639;1503.2033022498094;1511.7656763201721;1516.7194069097252;1545.4668139491857;1548.2427444850175;894.18182363137;907.3512438606297;909.9590518426687;922.8615707643772;923.8060007398387;8817.206451419403;8882.245009174292;8892.510911899779;8959.865602015974;8979.14698103679;976.9609215631438;982.4007355513131;989.9505024748734;994.3437331361412;1008.3647302199714;4859.246816743411;5339.687011423581;5363.061464243741;5414.29614150158;5468.562514998791;20079.29722459707;20631.69052464214;20960.580788384148;20973.58052838965;21876.781232187794;20312.187512499335;20548.38354849357;20966.58066838655;20984.580308393746;21223.363299101053;11091.279066860629;11340.773184536263;11412.201145919706;11417.771644567061;12023.21849079807;8804.207621314112;9268.397554158959;9272.536373181449;9412.058794120807;9554.283428742732;18783.15475803565;19117.90441047786;19609.11758970878;19719.605607887475;19916.402507924784;19817.405477835688;19982;20132.396028118594;20191.192352305745;20366.89816550967;11513.481893314989;11795.469203886018;11807.704807379541;11820.40897955116;11844.466998984894;14400.567982960529;14443.638909027359;14443.927780361031;14564.635884102558;14604.853951460775;11505.769884602429;11555.42222888869;11584.94207528957;11628.767424651629;11783.882161178279;10875.129989600971;11092.279001864854;11139.665810025712;11188.552457901593;11299.3785341807;9195.908040919505;9413.670521531714;9418.529073546433;9421;9422.19911307519;7452.9627351865065;7482.438817088631;7595.468317217742;7602.087749470099;7723.884141737766;7267.818304542217;7336.413086953137;7376.832089869206;7432.2567743225245;7495.925040749523 16:05:34 INFO - PID 1203 | |4;prototype.html;496.4315858342201;499.95250451207124;528.1971403466729;530.3847536857244;534.6417900006995;6406.327335629756;6416.45460135889;6422.839429014265;6439.388258115471;6460.2247730272375;6037.245344331958;6134.141220229171;6151.600145990518;6215.160953271307;6282.654454005028;5085.093090091215;5087.592992560586;5112.744362781856;5119.2321151827155;5124;5027.611884354938;5064.468230835746;5067.873303167414;5080.364954380702;5098.209777484477;5083.161278389061;5242.18746094356;5271.499207575274;5288.92066619001;5307.416184219752;5237.580993520509;5264.025825408632;5306.3632364116165;5346.5514742990745;5358.196270559409;4903.313536104948;4935.136351138536;4948.158813001763;4948.257761335793;4959.256111583255;6249.750009999594;6279.811605651837;6312.368763123697;6344.904826427607;6351;6058.000429929057;6061.424164704346;6082.239720034996;6094.603850749687;6118.938810611881;6540.247871494744;6573.046908198332;6604.834879128012;6692.4980626452725;6759.425448836855;5283.392409872837;5312.3890752563175;5328.973355133199;5368.32895888014;5368.62419630626;6931.792046238621;7010.544314619533;7074.009638650593;7138.964850096759;7143.24995875436;5504.366997795225;5522.392536820947;5544.445555444423;5553.333599967949;5559.221708960748;5596.377050655346;5601.691906945157;5642.435756424325;5650.321961364581;5679.8924209779525;6089.451949324582;6105.450509454082;6125.295591007002;6140.109684095781;6177.444030037318;6602.3397660233595;6661.3005634408655;6689.598624082473;6746;6749.966250168718;1831.8626103042325;1869.5886904880904;1909.7326374307609;1926.364299781069;1947.3573720672143;1436.6695659998259;1440.1143296872558;1453.2370505484705;1468.7209430208227;1474.8820094392422;5781.508571771363;5812.9709351452975;5852.502537284378;5861.9706901465215;5957.285125784847;6480.740770369227;6546.116274302908;6571.34286571339;6707.5975441473665;6748.865022699617;5780.653160810365;5795.82612521625;5827.213326200994;5848.912266316009;5866.837183278687;3559.982200089035;3569.8929032129076;3574.2315402188656;3593.7125029997537;3675.4854320395166;6496.109231391985;6594.680484282802;6616.139901812733;6656.36764507371;6666.266710661824;6993.76199616118;7015.351392422776;7054.576725396492;7106.360427561544;7118.216996130422;6562.639054852029;6621.801345959629;6629.602223866584;6642.704672588896;6650.933490665225;6798.796036118924;6812.420944219599;6827.351401616838;6894.586324820527;6907.067545881242;6730.226024007302;6808.455323574201;6825.897611535732;6867.244603093556;6883.586984780929;6507.284198738041;6646.567973081927;6649.135612370361;6694.690331835509;6727.486315578955;4969.204927211629;4985.975070124626;5034.44621091687;5068.619853511075;5084.186530155305;6750.594964302158;6803.489738269551;6870.519063665495;6918.584884906922;6939.132608423947;6567.408933196035;6600.702968366339;6616.172978377703;6632.966835165793;6634.237062737654;6609.3060228675295;6636.469082473294;6688.866222675617;6722.495812813961;6756.45948324123;6298.685065746603;6488.351164883474;6513.4463570595135;6558.934410655832;6615.4046135848;6348.619082855043;6567.934320656732;6589.110470086477;6603;6656;6354;6387.520935929917;6399.456046236122;6490.318516555693;6495.1556297681 16:05:34 INFO - PID 1203 | |5;yui.html;905.5834316214537;911.9954400228;925.4077390470098;930.0327659234397;935.5976929920132;19511.804881951175;20207.69688454674;20243.696344554806;20253.898730506364;22429.43926401841;20893.791062089374;20965.580688386224;21330.253441129564;21439.57120857582;21473.785262147372;926.8285367207088;950.3822515365033;952.1383148250809;953.0183910572122;953.2897990996718;945.7919257763281;980.5489474841546;989.9703008909709;994.6469003503761;995.9800803983916;1054.2830875005031;1057.3919996002292;1058.2063452410691;1059.4490864750326;1060.2737125069314;1044.0968562193723;1044.858944042556;1045.8012977534245;1046.7540128069897;1047.1308813684623;774.7598244544209;774.7869335932608;774.9070111586645;775.3564541430655;776.9689212431496;20243.797562024338;20254.189832406686;20481.79518204814;20493.07781149852;20550;21367.786322136733;21510.89244553783;21607;21863.453413664785;21909.671354929687;17275.654486910193;17476.475705728848;17594.736078958827;17688.292468301253;17703.5574110647;3752.6622603965775;3764.887053388403;3768.189839184561;3772.13240954582;3773.0378753417976;124811;131231;131774;133243.6675633241;134545;16043.197840108181;16228.350865965229;16329.755053674204;16649.83350166507;16832.410865619644;30822.845885770428;30869.69130308713;31044.844775775975;31310;31371.529427058613;7544.47188696786;7809.648565814552;8508.446950948168;8576.528290943934;8848.380613357132;4161.500619925629;4222.704410691284;4241.87274381769;4242.066745316026;4254.595813397784;4964.528369804862;5030.798768049311;5044.545990860766;5058.797648094035;5082.33929589151;1054;1075.4891426572315;1079.875814281352;1084.766775143348;1085.5006696919347;614.1494030767419;628.924529056507;637.1939496536885;649.9415052645285;652.2303681655692;11500.827487587692;11929.582464613697;12454.875451245553;13132.868671313356;13411.664708382174;678.0845858091537;678.25053316086;680.829792551862;685.3352248319122;694.4583225084442;5639.052663135822;5640.291787372231;5729.856753581111;5871.7651293947765;5896.498797602252;109943;111049;111553;111556;118333;97443;97640.51179744056;98875;102204.48897755612;103192;82093;83100;83457.58271208605;85622;87050.1294987042;10567.577296908037;10706.250562460553;10995.780084398428;12885.549005784753;12897.742045158857;40496.595034050464;41221;41472;42042.7897860521;42195.57804421917;68534.31465685279;70925;71209;73456.26543734495;74994.62502687452;15487.767683484532;17383.304667813147;17762.91118544399;18287.359942401952;19116.522086947938;19733.112009959874;19761.308354207533;20997.160113595895;21601.027953742432;21848.344549663536;18186.45440636783;19918.103685333906;19936.3022294219;19953.60092798165;20084.297049603192;15157.696846063238;18800.811991879906;19309.227630894606;19582.216711331388;19755.209791608173;18286.08569571543;18392.264309428007;18490.537736556693;18631.44105676832;18669.533261668566;12561.371931403575;12756.681082973;12867.549635762703;13330.133541319783;13376.799348009594;14034.157950523002;14439;14918.77621835695;14973.925130374279;15162 16:05:34 INFO - PID 1203 | __end_tp_report 16:05:34 INFO - PID 1203 | __start_cc_report 16:05:34 INFO - PID 1203 | _x_x_mozilla_cycle_collect,350 16:05:34 INFO - PID 1203 | __end_cc_report 16:05:34 INFO - PID 1203 | __startTimestamp1504652734144__endTimestamp 16:05:34 INFO - PID 1203 | 16:05:34 INFO - PID 1203 | ------- Summary: start ------- 16:05:34 INFO - PID 1203 | Number of tests: 6 16:05:34 INFO - PID 1203 | 16:05:34 INFO - PID 1203 | [#0] dojo.html Cycles:165 Average:6318.14 Median:6568.31 stddev:743.68 (11.3%) stddev-sans-first:705.17 16:05:34 INFO - PID 1203 | Values: 3221.7 3269.8 3325.0 3336.5 3353.8 6308.4 6328.7 6388.0 6473.8 6521.4 6468.3 6516.1 6537.6 6538.2 6583.0 5716.7 5831.4 5913.8 5978.2 5986.5 5818.2 5849.9 5850.8 5992.3 6001.5 5910.9 5967.9 5969.1 5986.3 5993.6 5872.9 5891.4 5973.3 5980.2 5996.7 5709.3 5801.0 5823.5 5934.1 5956.4 6359.3 6387.3 6406.6 6438.3 6445.8 6850.6 6856.0 6931.1 6943.6 6965.5 6916.8 6919.6 6944.9 7026.5 7047.5 6254.6 6331.8 6400.9 6494.3 6496.6 7076.8 7083.6 7084.6 7093.3 7105.3 6659.4 6681.9 6770.6 6775.0 6776.0 6763.9 6780.6 6844.6 6883.0 6895.5 6656.5 6727.8 6742.2 6781.2 6837.6 6730.7 6749.5 6793.5 6803.6 6812.4 5391.1 5402.5 5407.0 5407.6 5416.5 4718.5 4726.5 4739.2 4739.4 4743.6 5904.4 5909.1 5910.5 5932.4 5973.0 6921.7 6938.0 7003.1 7023.0 7032.0 5796.4 5938.7 5962.2 6045.0 6046.5 5856.8 5904.3 5920.9 5929.0 5930.6 6705.7 6718.9 6728.7 6738.9 6748.8 6891.2 6985.5 6988.2 6995.5 7004.8 6520.3 6604.4 6615.2 6615.5 6617.9 6696.0 6723.3 6727.5 6727.7 6730.1 6568.3 6765.9 6769.1 6769.2 6925.8 6715.9 6721.2 6790.2 6808.0 6813.1 6473.5 6566.8 6610.6 6707.8 6802.1 6499.8 6555.3 6583.5 6585.1 6585.7 6588.9 6651.2 6687.6 6692.2 6725.3 6392.4 6499.9 6530.2 6601.1 6651.5 16:05:34 INFO - PID 1203 | 16:05:34 INFO - PID 1203 | [#1] ext.html Cycles:180 Average:37543.80 Median:33505.08 stddev:54730.14 (163.3%) stddev-sans-first:54814.08 16:05:34 INFO - PID 1203 | Values: 786.3 850.4 880.6 1131.8 1159.1 30366.5 39556.2 40966.8 40980.6 41043.2 40723.8 40749.4 40792.8 41027.4 41097.6 7539.2 7697.0 7702.7 7706.9 7725.2 7599.4 7685.6 7705.4 7713.1 7713.2 7255.6 7828.7 7832.6 7833.4 7837.7 7681.3 7698.3 7703.7 7729.1 7773.2 7499.6 7584.6 7586.7 7632.7 7634.1 36572.6 36877.8 36890.1 37134.0 37274.0 3527.5 3561.1 3570.9 3572.0 3572.1 30349.4 30474.5 30520.7 30527.4 30593.4 4685.4 4706.0 4727.2 4736.0 4741.8 188381.0 190258.0 190282.0 190901.0 191149.0 46824.8 47135.0 47213.5 47289.5 47331.0 49421.0 49552.0 49568.5 49584.3 49610.3 15352.4 15570.2 15584.3 15602.0 15622.4 12547.4 12554.9 12591.1 12603.1 12609.2 12319.4 12572.6 14136.4 14147.8 14204.9 5596.9 5705.2 5786.9 5788.1 5928.2 1012.1 1022.3 1043.4 1048.6 1065.9 22862.3 23135.2 23181.5 23328.5 23503.3 1128.1 1138.4 1152.1 1153.2 1161.5 12275.6 12455.5 12482.6 12526.0 12550.3 232403.0 233719.0 233861.0 236292.0 236750.0 53355.0 53761.2 53766.2 53918.5 54006.5 1438.3 1453.0 1469.5 1485.7 1497.4 23532.4 23536.9 23569.4 23630.5 23692.3 37442.6 37471.4 37501.2 37532.2 37557.6 205288.0 205307.0 205894.0 206798.0 207879.0 32042.4 33371.2 33499.5 33510.7 33519.0 37580.4 38645.6 41025.8 43215.0 43644.6 31615.0 31896.0 32157.7 32424.8 32599.7 31554.0 31595.4 31616.8 31873.5 31875.0 35212.1 35319.5 35516.0 35563.5 35567.8 30535.7 30789.7 30790.4 31021.5 31447.4 23176.3 23512.0 23653.6 23736.4 23794.4 16:05:34 INFO - PID 1203 | 16:05:34 INFO - PID 1203 | [#2] jquery.html Cycles:180 Average:35420.46 Median:23111.54 stddev:86229.01 (373.1%) stddev-sans-first:86431.17 16:05:34 INFO - PID 1203 | Values: 556.7 563.3 567.8 581.5 589.5 21977.8 23032.7 23038.1 23050.3 23067.1 23658.5 24799.4 25811.5 26018.9 26155.8 6603.3 6738.1 6745.9 6747.7 6750.1 6090.6 6353.2 6633.0 6667.7 6668.0 6639.6 6645.0 6649.0 6653.0 6653.6 6656.3 6656.4 6658.3 6658.5 6661.0 6135.5 6138.0 6147.6 6160.4 6170.3 28039.3 28509.9 28701.7 28724.4 29219.0 2032.8 2065.5 2066.3 2069.3 2072.8 19285.6 19840.9 19964.4 20007.3 20046.6 2369.9 2387.4 2396.3 2399.8 2442.8 135613.3 139081.0 139392.0 139554.3 139808.0 13817.5 14433.8 14579.8 14783.3 14958.4 21742.9 23094.2 23102.5 23120.5 23185.0 10069.4 10078.2 10102.7 10340.9 10379.0 9264.3 9342.6 9392.0 9414.7 9425.9 9100.8 9113.6 9191.5 9352.9 9545.5 2597.6 2622.2 2644.2 2674.5 2739.2 957.5 961.8 966.5 981.4 999.4 19453.5 19748.8 19968.2 20007.7 23821.6 989.9 1050.2 1055.2 1061.1 1062.0 10608.3 11415.9 13445.7 13869.9 14430.7 491225.0 515893.0 516788.0 517183.0 529129.0 112716.0 113467.0 116768.0 117985.0 118777.4 1253.4 1256.8 1280.5 1280.7 1283.7 21268.8 21385.8 21480.4 21509.4 21549.6 48095.8 52093.0 57293.0 57739.7 58774.1 64937.4 65036.3 65696.7 65947.0 66197.7 14260.1 14528.9 14671.4 14674.3 14696.4 23156.8 23292.7 23347.3 23348.0 23555.6 18107.3 18151.1 18294.0 18418.2 18427.4 16547.8 16875.2 17096.1 17123.9 17502.7 18573.8 18845.5 19013.9 19094.9 19170.8 14313.4 14360.6 14452.6 14458.5 14531.5 18060.7 18168.0 18199.2 18510.7 18658.0 16:05:34 INFO - PID 1203 | 16:05:34 INFO - PID 1203 | [#3] mootools.html Cycles:180 Average:9474.02 Median:2631.60 stddev:6680.45 (253.9%) stddev-sans-first:6671.40 16:05:34 INFO - PID 1203 | Values: 1363.9 1394.7 1425.3 1425.6 1429.8 14703.8 15233.0 15900.5 15910.0 15910.1 16230.6 16532.0 16533.3 16543.3 16546.2 2701.6 2763.2 2764.5 2764.8 2764.9 2635.1 2666.1 2764.5 2827.9 2831.7 2745.7 2777.3 2777.8 2783.5 2785.4 2610.8 2627.9 2679.7 2681.3 2686.7 2594.9 2621.3 2623.7 2628.5 2634.7 15118.2 15488.6 15491.9 15496.1 15500.8 7899.6 8090.0 8131.1 8179.7 8183.5 5051.4 5188.4 5317.7 5349.0 5476.5 1880.8 1907.0 1933.0 1961.2 1981.6 25053.6 25285.0 25312.0 25318.0 26652.5 6627.4 6734.9 6818.7 6853.8 6873.5 17671.8 17699.9 17708.7 17723.1 17726.9 7853.4 7913.0 7949.0 7950.5 7954.2 7263.9 7303.9 7352.2 7364.3 7411.4 5043.9 5224.1 5229.7 5258.1 5308.2 1503.2 1511.8 1516.7 1545.5 1548.2 894.2 907.4 910.0 922.9 923.8 8817.2 8882.2 8892.5 8959.9 8979.1 977.0 982.4 990.0 994.3 1008.4 4859.2 5339.7 5363.1 5414.3 5468.6 20079.3 20631.7 20960.6 20973.6 21876.8 20312.2 20548.4 20966.6 20984.6 21223.4 11091.3 11340.8 11412.2 11417.8 12023.2 8804.2 9268.4 9272.5 9412.1 9554.3 18783.2 19117.9 19609.1 19719.6 19916.4 19817.4 19982.0 20132.4 20191.2 20366.9 11513.5 11795.5 11807.7 11820.4 11844.5 14400.6 14443.6 14443.9 14564.6 14604.9 11505.8 11555.4 11584.9 11628.8 11783.9 10875.1 11092.3 11139.7 11188.6 11299.4 9195.9 9413.7 9418.5 9421.0 9422.2 7453.0 7482.4 7595.5 7602.1 7723.9 7267.8 7336.4 7376.8 7432.3 7495.9 16:05:34 INFO - PID 1203 | 16:05:34 INFO - PID 1203 | [#4] prototype.html Cycles:180 Average:5684.90 Median:6281.23 stddev:1533.73 (24.4%) stddev-sans-first:1487.77 16:05:34 INFO - PID 1203 | Values: 496.4 500.0 528.2 530.4 534.6 6406.3 6416.5 6422.8 6439.4 6460.2 6037.2 6134.1 6151.6 6215.2 6282.7 5085.1 5087.6 5112.7 5119.2 5124.0 5027.6 5064.5 5067.9 5080.4 5098.2 5083.2 5242.2 5271.5 5288.9 5307.4 5237.6 5264.0 5306.4 5346.6 5358.2 4903.3 4935.1 4948.2 4948.3 4959.3 6249.8 6279.8 6312.4 6344.9 6351.0 6058.0 6061.4 6082.2 6094.6 6118.9 6540.2 6573.0 6604.8 6692.5 6759.4 5283.4 5312.4 5329.0 5368.3 5368.6 6931.8 7010.5 7074.0 7139.0 7143.2 5504.4 5522.4 5544.4 5553.3 5559.2 5596.4 5601.7 5642.4 5650.3 5679.9 6089.5 6105.5 6125.3 6140.1 6177.4 6602.3 6661.3 6689.6 6746.0 6750.0 1831.9 1869.6 1909.7 1926.4 1947.4 1436.7 1440.1 1453.2 1468.7 1474.9 5781.5 5813.0 5852.5 5862.0 5957.3 6480.7 6546.1 6571.3 6707.6 6748.9 5780.7 5795.8 5827.2 5848.9 5866.8 3560.0 3569.9 3574.2 3593.7 3675.5 6496.1 6594.7 6616.1 6656.4 6666.3 6993.8 7015.4 7054.6 7106.4 7118.2 6562.6 6621.8 6629.6 6642.7 6650.9 6798.8 6812.4 6827.4 6894.6 6907.1 6730.2 6808.5 6825.9 6867.2 6883.6 6507.3 6646.6 6649.1 6694.7 6727.5 4969.2 4986.0 5034.4 5068.6 5084.2 6750.6 6803.5 6870.5 6918.6 6939.1 6567.4 6600.7 6616.2 6633.0 6634.2 6609.3 6636.5 6688.9 6722.5 6756.5 6298.7 6488.4 6513.4 6558.9 6615.4 6348.6 6567.9 6589.1 6603.0 6656.0 6354.0 6387.5 6399.5 6490.3 6495.2 16:05:34 INFO - PID 1203 | 16:05:34 INFO - PID 1203 | [#5] yui.html Cycles:180 Average:24218.87 Median:21163.71 stddev:32871.13 (155.3%) stddev-sans-first:32916.72 16:05:34 INFO - PID 1203 | Values: 905.6 912.0 925.4 930.0 935.6 19511.8 20207.7 20243.7 20253.9 22429.4 20893.8 20965.6 21330.3 21439.6 21473.8 926.8 950.4 952.1 953.0 953.3 945.8 980.5 990.0 994.6 996.0 1054.3 1057.4 1058.2 1059.4 1060.3 1044.1 1044.9 1045.8 1046.8 1047.1 774.8 774.8 774.9 775.4 777.0 20243.8 20254.2 20481.8 20493.1 20550.0 21367.8 21510.9 21607.0 21863.5 21909.7 17275.7 17476.5 17594.7 17688.3 17703.6 3752.7 3764.9 3768.2 3772.1 3773.0 124811.0 131231.0 131774.0 133243.7 134545.0 16043.2 16228.4 16329.8 16649.8 16832.4 30822.8 30869.7 31044.8 31310.0 31371.5 7544.5 7809.6 8508.4 8576.5 8848.4 4161.5 4222.7 4241.9 4242.1 4254.6 4964.5 5030.8 5044.5 5058.8 5082.3 1054.0 1075.5 1079.9 1084.8 1085.5 614.1 628.9 637.2 649.9 652.2 11500.8 11929.6 12454.9 13132.9 13411.7 678.1 678.3 680.8 685.3 694.5 5639.1 5640.3 5729.9 5871.8 5896.5 109943.0 111049.0 111553.0 111556.0 118333.0 97443.0 97640.5 98875.0 102204.5 103192.0 82093.0 83100.0 83457.6 85622.0 87050.1 10567.6 10706.3 10995.8 12885.5 12897.7 40496.6 41221.0 41472.0 42042.8 42195.6 68534.3 70925.0 71209.0 73456.3 74994.6 15487.8 17383.3 17762.9 18287.4 19116.5 19733.1 19761.3 20997.2 21601.0 21848.3 18186.5 19918.1 19936.3 19953.6 20084.3 15157.7 18800.8 19309.2 19582.2 19755.2 18286.1 18392.3 18490.5 18631.4 18669.5 12561.4 12756.7 12867.5 13330.1 13376.8 14034.2 14439.0 14918.8 14973.9 15162.0 16:05:34 INFO - PID 1203 | -------- Summary: end -------- 16:05:34 INFO - PID 1203 | 16:05:34 INFO - TEST-INFO | 1203: exit 0 16:05:34 INFO - TEST-OK | dromaeo_css | took 1109778ms 16:05:34 INFO - TEST-START | kraken 16:05:34 INFO - Initialising browser for kraken test... 16:05:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54880/getInfo.html -profile /tmp/tmpYXzkJ5/profile 16:05:34 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/firefox/firefox http://localhost:54880/getInfo.html) 16:05:40 INFO - TEST-INFO | 1660: exit 0 16:05:40 INFO - Browser initialized. 16:05:40 INFO - Running cycle 1/1 for kraken test... 16:05:40 INFO - Using env: {'DISPLAY': ':0', 16:05:40 INFO - 'HOME': '/home/cltbld', 16:05:40 INFO - 'JSGC_DISABLE_POISONING': '1', 16:05:40 INFO - 'LANG': 'en_US.UTF-8', 16:05:40 INFO - 'LANGUAGE': 'en_US:en', 16:05:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:05:40 INFO - 'LOGNAME': 'cltbld', 16:05:40 INFO - 'MAIL': '/var/mail/cltbld', 16:05:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:05:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:05:40 INFO - 'MOZ_CRASHREPORTER': '1', 16:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:05:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:05:40 INFO - 'MOZ_NO_REMOTE': '1', 16:05:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:05:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:05:40 INFO - 'NO_EM_RESTART': '1', 16:05:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:05:40 INFO - 'PWD': '/builds/slave/test', 16:05:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:05:40 INFO - 'RUST_BACKTRACE': 'full', 16:05:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:05:40 INFO - 'SHELL': '/bin/bash', 16:05:40 INFO - 'SHLVL': '1', 16:05:40 INFO - 'STYLO_FORCE_ENABLED': '1', 16:05:40 INFO - 'TERM': 'linux', 16:05:40 INFO - 'TMOUT': '86400', 16:05:40 INFO - 'USER': 'cltbld', 16:05:40 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617', 16:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:05:40 INFO - '_': '/tools/buildbot/bin/python'} 16:05:40 INFO - TEST-INFO | started process 1862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYXzkJ5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:05:41 INFO - PID 1862 | 16:05:41 INFO - PID 1862 | (/builds/slave/test/build/application/firefox/firefox:1914): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:05:41 INFO - PID 1862 | 16:05:51 INFO - PID 1862 | RSS: Main: 167276544 16:05:51 INFO - PID 1862 | 16:05:54 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54880/tests/kraken/driver.html?testName=audio-beat-detection) 16:05:55 INFO - PID 1862 | RSS: Main: 172875776 16:05:55 INFO - PID 1862 | 16:05:58 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54880/tests/kraken/driver.html?testName=audio-dft) 16:05:58 INFO - PID 1862 | RSS: Main: 177020928 16:05:58 INFO - PID 1862 | 16:06:01 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54880/tests/kraken/driver.html?testName=audio-fft) 16:06:02 INFO - PID 1862 | RSS: Main: 170491904 16:06:02 INFO - PID 1862 | 16:06:04 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54880/tests/kraken/driver.html?testName=audio-oscillator) 16:06:05 INFO - PID 1862 | RSS: Main: 171298816 16:06:05 INFO - PID 1862 | 16:06:07 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54880/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:06:07 INFO - PID 1862 | RSS: Main: 171773952 16:06:07 INFO - PID 1862 | 16:06:10 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54880/tests/kraken/driver.html?testName=imaging-darkroom) 16:06:11 INFO - PID 1862 | RSS: Main: 172666880 16:06:11 INFO - PID 1862 | 16:06:15 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54880/tests/kraken/driver.html?testName=imaging-desaturate) 16:06:15 INFO - PID 1862 | RSS: Main: 172552192 16:06:15 INFO - PID 1862 | 16:06:18 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54880/tests/kraken/driver.html?testName=json-parse-financial) 16:06:19 INFO - PID 1862 | RSS: Main: 173273088 16:06:19 INFO - PID 1862 | 16:06:20 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54880/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:06:21 INFO - PID 1862 | RSS: Main: 172965888 16:06:21 INFO - PID 1862 | 16:06:23 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54880/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:06:23 INFO - PID 1862 | RSS: Main: 173309952 16:06:23 INFO - PID 1862 | 16:06:25 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54880/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:06:26 INFO - PID 1862 | RSS: Main: 172969984 16:06:26 INFO - PID 1862 | 16:06:29 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54880/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:06:29 INFO - PID 1862 | RSS: Main: 173367296 16:06:29 INFO - PID 1862 | 16:06:32 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54880/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:06:33 INFO - PID 1862 | RSS: Main: 173342720 16:06:33 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | Cycle 1(1): loaded http://localhost:54880/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54880/tests/kraken/driver.html?testName=ai-astar) 16:06:35 INFO - PID 1862 | RSS: Main: 175120384 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | __start_tp_report 16:06:35 INFO - PID 1862 | _x_x_mozilla_page_load 16:06:35 INFO - PID 1862 | _x_x_mozilla_page_load_details 16:06:35 INFO - PID 1862 | |i|pagename|runs| 16:06:35 INFO - PID 1862 | |0;ai-astar;98;106;100;107;114;108;107;98;111;107 16:06:35 INFO - PID 1862 | |1;audio-beat-detection;152;114;143;167;127;142;142;114;168;118 16:06:35 INFO - PID 1862 | |2;audio-dft;164;164;162;163;162;162;165;165;172;163 16:06:35 INFO - PID 1862 | |3;audio-fft;74;75;74;169;75;77;75;73;169;73 16:06:35 INFO - PID 1862 | |4;audio-oscillator;79;79;145;80;80;81;132;79;79;124 16:06:35 INFO - PID 1862 | |5;imaging-gaussian-blur;115;115;114;115;114;114;115;115;115;115 16:06:35 INFO - PID 1862 | |6;imaging-darkroom;148;148;156;149;151;149;148;149;148;150 16:06:35 INFO - PID 1862 | |7;imaging-desaturate;94;94;94;94;94;98;93;94;94;94 16:06:35 INFO - PID 1862 | |8;json-parse-financial;53;54;55;53;60;54;53;53;54;53 16:06:35 INFO - PID 1862 | |9;json-stringify-tinderbox;38;39;38;38;38;38;38;39;39;38 16:06:35 INFO - PID 1862 | |10;stanford-crypto-aes;74;80;78;75;75;75;75;75;77;75 16:06:35 INFO - PID 1862 | |11;stanford-crypto-ccm;129;121;131;130;115;124;122;118;118;158 16:06:35 INFO - PID 1862 | |12;stanford-crypto-pbkdf2;175;171;175;176;174;169;171;167;182;178 16:06:35 INFO - PID 1862 | |13;stanford-crypto-sha256-iterative;69;64;65;65;64;68;64;65;68;64 16:06:35 INFO - PID 1862 | __end_tp_report 16:06:35 INFO - PID 1862 | __start_cc_report 16:06:35 INFO - PID 1862 | _x_x_mozilla_cycle_collect,984 16:06:35 INFO - PID 1862 | __end_cc_report 16:06:35 INFO - PID 1862 | __startTimestamp1504652795637__endTimestamp 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | ------- Summary: start ------- 16:06:35 INFO - PID 1862 | Number of tests: 14 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#0] ai-astar Cycles:10 Average:105.60 Median:109.50 stddev:5.36 (4.9%) stddev-sans-first:4.93 16:06:35 INFO - PID 1862 | Values: 98.0 106.0 100.0 107.0 114.0 108.0 107.0 98.0 111.0 107.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:142.50 stddev:20.17 (14.2%) stddev-sans-first:20.81 16:06:35 INFO - PID 1862 | Values: 152.0 114.0 143.0 167.0 127.0 142.0 142.0 114.0 168.0 118.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#2] audio-dft Cycles:10 Average:164.20 Median:164.00 stddev:2.97 (1.8%) stddev-sans-first:3.15 16:06:35 INFO - PID 1862 | Values: 164.0 164.0 162.0 163.0 162.0 162.0 165.0 165.0 172.0 163.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#3] audio-fft Cycles:10 Average:93.40 Median:74.50 stddev:39.86 (53.5%) stddev-sans-first:41.66 16:06:35 INFO - PID 1862 | Values: 74.0 75.0 74.0 169.0 75.0 77.0 75.0 73.0 169.0 73.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:79.00 stddev:26.61 (33.7%) stddev-sans-first:27.52 16:06:35 INFO - PID 1862 | Values: 79.0 79.0 145.0 80.0 80.0 81.0 132.0 79.0 79.0 124.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:115.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 16:06:35 INFO - PID 1862 | Values: 115.0 115.0 114.0 115.0 114.0 114.0 115.0 115.0 115.0 115.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#6] imaging-darkroom Cycles:10 Average:149.60 Median:149.00 stddev:2.46 (1.7%) stddev-sans-first:2.54 16:06:35 INFO - PID 1862 | Values: 148.0 148.0 156.0 149.0 151.0 149.0 148.0 149.0 148.0 150.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:1.34 (1.4%) stddev-sans-first:1.41 16:06:35 INFO - PID 1862 | Values: 94.0 94.0 94.0 94.0 94.0 98.0 93.0 94.0 94.0 94.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.00 stddev:2.15 (4.0%) stddev-sans-first:2.24 16:06:35 INFO - PID 1862 | Values: 53.0 54.0 55.0 53.0 60.0 54.0 53.0 53.0 54.0 53.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 16:06:35 INFO - PID 1862 | Values: 38.0 39.0 38.0 38.0 38.0 38.0 38.0 39.0 39.0 38.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:75.00 stddev:1.85 (2.5%) stddev-sans-first:1.83 16:06:35 INFO - PID 1862 | Values: 74.0 80.0 78.0 75.0 75.0 75.0 75.0 75.0 77.0 75.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#11] stanford-crypto-ccm Cycles:10 Average:126.60 Median:126.50 stddev:12.31 (9.7%) stddev-sans-first:13.03 16:06:35 INFO - PID 1862 | Values: 129.0 121.0 131.0 130.0 115.0 124.0 122.0 118.0 118.0 158.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:175.00 stddev:4.44 (2.5%) stddev-sans-first:4.69 16:06:35 INFO - PID 1862 | Values: 175.0 171.0 175.0 176.0 174.0 169.0 171.0 167.0 182.0 178.0 16:06:35 INFO - PID 1862 | 16:06:35 INFO - PID 1862 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.00 stddev:1.96 (3.0%) stddev-sans-first:1.64 16:06:35 INFO - PID 1862 | Values: 69.0 64.0 65.0 65.0 64.0 68.0 64.0 65.0 68.0 64.0 16:06:35 INFO - PID 1862 | -------- Summary: end -------- 16:06:35 INFO - PID 1862 | 16:06:36 INFO - PID 1862 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:06:36 INFO - TEST-INFO | 1862: exit 0 16:06:36 INFO - TEST-OK | kraken | took 61424ms 16:06:36 INFO - SUITE-END | took 1171s 16:06:36 INFO - Completed test suite (00:19:31) 16:06:36 INFO - javascript benchmark 16:06:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8668.439582986504, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3221.742260619151, 3269.7547683923713, 3324.9667503324977, 3336.532885396044, 3353.8323083845803, 6308.369163083689, 6328.683565821702, 6388.041793730943, 6473.773417930373, 6521.445677117451, 6468.256150542689, 6516.055172000057, 6537.575057621251, 6538.215414150297, 6583.012548117785, 5716.685582292972, 5831.416858314155, 5913.822585322437, 5978.192943952576, 5986.491148252404, 5818.156367326735, 5849.912251316234, 5850.824475265749, 5992.34084250732, 6001.5198784097165, 5910.911336329958, 5967.850803729898, 5969.074793407029, 5986.2517185351835, 5993.61041532299, 5872.853178670524, 5891.410858914117, 5973.342932277446, 5980.192673989, 5996.670183139926, 5709.286339207599, 5801.0428279333855, 5823.475887170132, 5934.13954976526, 5956.404359564052, 6359.300476947532, 6387.2654644715985, 6406.583572067847, 6438.259600145996, 6445.774397896098, 6850.554713943628, 6856.040154378391, 6931.064306318633, 6943.583384996917, 6965.5124141310125, 6916.827079323007, 6919.619420931846, 6944.895826562605, 7026.543274687129, 7047.506674532785, 6254.624722516618, 6331.841703957438, 6400.871982560369, 6494.253160886464, 6496.642684652342, 7076.823079423056, 7083.574985500886, 7084.574925504487, 7093.290670932969, 7105.32499412555, 6659.433948114464, 6681.899771503431, 6770.627615481098, 6774.966125169342, 6775.966120169466, 6763.93236067633, 6780.559263647946, 6844.623545704934, 6883.0, 6895.5173137879865, 6656.467482601381, 6727.764528241488, 6742.224644165984, 6781.152355955505, 6837.555558888656, 6730.697118629673, 6749.493787965922, 6793.45652347811, 6803.591784492946, 6812.386885180357, 5391.083515802323, 5402.540784033401, 5406.972965135149, 5407.621466497386, 5416.51251387377, 4718.528147185254, 4726.456457507362, 4739.194336962724, 4739.3601863748645, 4743.573078422958, 5904.409559044061, 5909.113632955005, 5910.527157827363, 5932.377100404396, 5973.04431291, 6921.688524016431, 6938.0, 7003.054587630707, 7023.0, 7032.0, 5796.449337312949, 5938.673372964442, 5962.22491076166, 6044.972354699712, 6046.546509011754, 5856.824295271149, 5904.321003084614, 5920.8815823684145, 5928.970355148197, 5930.644161350333, 6705.6982435790505, 6718.865622687519, 6728.697208625624, 6738.932610673831, 6748.831279218059, 6891.173059232924, 6985.545939513915, 6988.231294557696, 6995.475339349569, 7004.824879378159, 6520.315366886598, 6604.3725846044545, 6615.239247486601, 6615.536912416084, 6617.867642647217, 6695.966520167368, 6723.294054124247, 6727.461803055646, 6727.663616819237, 6730.125083739083, 6568.310327415553, 6765.864682706417, 6769.052332673528, 6769.153855768029, 6925.826854328682, 6715.865682686416, 6721.1598550181225, 6790.151231096113, 6808.012838138343, 6813.080234168522, 6473.546851720334, 6566.770163044462, 6610.603363798187, 6707.765228216989, 6802.115724955724, 6499.805005849832, 6555.2789193187755, 6583.506237032338, 6585.1439312889015, 6585.670716464062, 6588.901166482602, 6651.235107962646, 6687.6321802301345, 6692.230393504809, 6725.327467253235, 6392.4246817786625, 6499.902501462388, 6530.151080359523, 6601.108850305147, 6651.534392592472], "value": 6263.097075766002, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.3355464632388, 850.3749743938205, 880.6213328268842, 1131.7679875625493, 1159.1364433497047, 30366.54450183246, 39556.20887582246, 40966.79516602413, 40980.590194098084, 41043.179136417326, 40723.79638101805, 40749.388759168636, 40792.796036019776, 41027.38458923119, 41097.58902410975, 7539.2460753924715, 7696.961515192416, 7702.730404435846, 7706.884396734025, 7725.227477252286, 7599.35405490534, 7685.6157192140445, 7705.383569314469, 7713.112992005907, 7713.190115037924, 7255.600941948191, 7828.686852525892, 7832.56920869352, 7833.373330133604, 7837.725679601187, 7681.3086822186, 7698.3071523562585, 7703.653335599912, 7729.149793522708, 7773.222677732209, 7499.6250187490405, 7584.582847943361, 7586.696532138708, 7632.694692212305, 7634.083909930789, 36572.63427365719, 36877.81561092204, 36890.07774805625, 37134.0, 37274.0, 3527.4532447470683, 3561.0919215599856, 3570.946435803465, 3571.982140089283, 3572.142685755426, 30349.393012139633, 30474.54288185679, 30520.69479305223, 30527.38945221083, 30593.38813223723, 4685.390899183119, 4705.952940470585, 4727.22000869856, 4735.9817639207395, 4741.786619602126, 188381.0, 190258.0, 190282.0, 190901.04549477302, 191149.0, 46824.7658761704, 47135.0, 47213.52786472091, 47289.52710472851, 47331.0, 49421.0, 49552.0, 49568.504314956386, 49584.256236156485, 49610.255846162334, 15352.385904563916, 15570.221488925508, 15584.298706558233, 15602.0, 15622.375104995901, 12547.372631368395, 12554.874451255371, 12591.118621696392, 12603.117781755189, 12609.180403273942, 12319.384030798425, 12572.559960401342, 14136.434542618386, 14147.787783183261, 14204.928975355057, 5596.916046259309, 5705.22979397776, 5786.942130578725, 5788.102844059177, 5928.229330187048, 1012.1447376966452, 1022.2895087913977, 1043.3687618990402, 1048.5596049659162, 1065.8507808906759, 22862.31413057611, 23135.190268340528, 23181.53636927252, 23328.533429331317, 23503.294901152993, 1128.114430172311, 1138.362516990488, 1152.0714364018656, 1153.1927650644582, 1161.5063597970998, 12275.631731047904, 12455.501779928702, 12482.625521234377, 12526.0, 12550.309732964775, 232403.0, 233719.0, 233861.0, 236292.0, 236750.0, 53355.0, 53761.19358209552, 53766.19350709742, 53918.46081539213, 54006.45993540014, 1438.3311760031636, 1452.9740873072742, 1469.5077149155152, 1485.6731519065788, 1497.4160077569538, 23532.411689707893, 23536.940837662005, 23569.41076473102, 23630.52738945177, 23692.289231323088, 37442.625573745005, 37471.43792843055, 37501.2499749998, 37532.2493550122, 37557.62442375541, 205288.0, 205307.0, 205894.0, 206798.0, 207879.0, 32042.35915281728, 33371.16572085717, 33499.49750753692, 33510.66489335173, 33519.0, 37580.43629345507, 38645.61354386533, 41025.79487102546, 43215.0, 43644.56355436405, 31615.0, 31896.0, 32157.678423215468, 32424.83787581047, 32599.674003259664, 31554.0, 31595.368092638477, 31616.841915791196, 31873.521897172024, 31875.0, 35212.11969700778, 35319.470207947415, 35516.0, 35563.46654800232, 35567.82216088903, 30535.694643053284, 30789.692103078683, 30790.384192316476, 31021.534676979412, 31447.371052579278, 23176.3047108587, 23512.0, 23653.645195322428, 23736.406265588732, 23794.40513987095], "value": 17039.359870538225, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [556.69381839988, 563.335264388022, 567.7615401531357, 581.450529249859, 589.5048159545981, 21977.780222197773, 23032.65451018236, 23038.078476860926, 23050.30849074526, 23067.077316907304, 23658.5268294634, 24799.38001549962, 25811.483770324583, 26018.869905650445, 26155.82298796554, 6603.3396660334065, 6738.12404387429, 6745.898811517831, 6747.696353664097, 6750.122484077063, 6090.60411073281, 6353.174087368636, 6633.03820945965, 6667.666616669171, 6668.0, 6639.634820084893, 6644.966775166142, 6648.9667551662415, 6653.035309880064, 6653.634050127241, 6656.334366563329, 6656.367645073735, 6658.30087840777, 6658.533902626818, 6660.966695166493, 6135.478484328837, 6137.969310153465, 6147.631142131441, 6160.4147605977805, 6170.321264660884, 28039.29901752452, 28509.85745071282, 28701.712982870115, 28724.425511489862, 29219.0, 2032.8475364347732, 2065.452655046414, 2066.349100033485, 2069.265410779167, 2072.751269847628, 19285.614287714307, 19840.900795496076, 19964.401067967985, 20007.29974450902, 20046.599068018702, 2369.9413567841643, 2387.403149212697, 2396.317049640844, 2399.820013498977, 2442.8412153209983, 135613.3219333907, 139081.0, 139392.0, 139554.30222848922, 139808.0, 13817.516386926409, 14433.783493247609, 14579.781303280459, 14783.260836958108, 14958.401663933539, 21742.89128554379, 23094.191703290304, 23102.53794924092, 23120.53758924812, 23185.0, 10069.446180460145, 10078.244131690006, 10102.69691909244, 10340.896591034143, 10379.0, 9264.25885929124, 9342.626294948126, 9391.953040234892, 9414.717558473258, 9425.90574094264, 9100.81798364029, 9113.589888455035, 9191.540422978824, 9352.859707104399, 9545.47499887499, 2597.584176807492, 2622.1871219921886, 2644.1538707613763, 2674.5453272943255, 2739.2330147558714, 957.5355952363099, 961.778790878088, 966.5360626898987, 981.3572110267786, 999.3604093380243, 19453.51366215856, 19748.802511974984, 19968.20127194925, 20007.699884501453, 23821.642675359883, 989.9307048506679, 1050.1546255264432, 1055.2191378379944, 1061.0609610494769, 1061.9681409557725, 10608.310459820086, 11415.942920285346, 13445.663858403617, 13869.861301387058, 14430.711385772225, 491225.0, 515893.0, 516788.0, 517183.0, 529129.0, 112716.0, 113467.0, 116768.0, 117985.0, 118777.40611296888, 1253.3607859991287, 1256.761215369077, 1280.5134049061296, 1280.6670265730977, 1283.6983308922395, 21268.7873121273, 21385.786142139, 21480.355589332346, 21509.354719358445, 21549.569008619426, 48095.75952120217, 52093.0, 57293.0, 57739.711301444906, 58774.11838822506, 64937.35062649313, 65036.34963650303, 65696.67151664403, 65947.0, 66197.66901165464, 14260.144391336553, 14528.927355363156, 14671.413143474141, 14674.339654715777, 14696.41214351414, 23156.76843231546, 23292.6506102412, 23347.299581012598, 23348.0, 23555.646665300377, 18107.275708971494, 18151.09244537794, 18294.0, 18418.171182297097, 18427.355042573447, 16547.83452165511, 16875.24061417264, 17096.14519274056, 17123.914380428017, 17502.73745893838, 18573.81426185721, 18845.528861778566, 19013.90493047526, 19094.90452547784, 19170.808291916903, 14313.355898984362, 14360.640983975483, 14452.566423007327, 14458.493952711604, 14531.491397801026, 18060.729089063938, 18168.0, 18199.181036853108, 18510.722339164655, 18657.9738114405], "value": 12109.82036373224, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.8840698540625, 1394.7210557888425, 1425.2873563218384, 1425.6293363725429, 1429.756941319975, 14703.779443308333, 15233.009854359472, 15900.52298431049, 15910.0, 15910.124943128138, 16230.594235144128, 16532.0, 16533.338666453357, 16543.25555350009, 16546.172691365413, 2701.5677491601346, 2763.226296636935, 2764.530029894913, 2764.8064635475525, 2764.9308767280777, 2635.0908936416904, 2666.1068542038443, 2764.543850264704, 2827.8586070696488, 2831.7168283171723, 2745.7117002714726, 2777.319556708599, 2777.847218402987, 2783.471140483312, 2785.4289870576586, 2610.8041896857717, 2627.8686065696734, 2679.705232424432, 2681.316264352597, 2686.7313268673074, 2594.883230254643, 2621.279148234232, 2623.6851577810594, 2628.47430513898, 2634.7233540478164, 15118.244087795567, 15488.612784680361, 15491.922540387339, 15496.070235785775, 15500.767488487681, 7899.644515996794, 8089.988751406074, 8131.14622964592, 8179.672813087469, 8183.549904755236, 5051.368578927634, 5188.377394712621, 5317.707526086064, 5348.973255133699, 5476.452354764531, 1880.8213219744102, 1906.961860762777, 1932.9710054349196, 1961.1763059515038, 1981.6135853508572, 25053.62419563708, 25285.0, 25312.0, 25318.0, 26652.466950660877, 6627.403533681992, 6734.865302693918, 6818.659067046628, 6853.7943861684225, 6873.48448866327, 17671.823281767018, 17699.91150044267, 17708.734368984475, 17723.113844307733, 17726.91136544309, 7853.41099417546, 7912.960435197902, 7948.966634337499, 7950.522968621901, 7954.204579542115, 7263.92736072643, 7303.89044164338, 7352.19125896151, 7364.263573642593, 7411.407087432993, 5043.9243411348125, 5224.138017227191, 5229.712365819841, 5258.053550360972, 5308.150695888639, 1503.2033022498094, 1511.7656763201721, 1516.7194069097252, 1545.4668139491857, 1548.2427444850175, 894.18182363137, 907.3512438606297, 909.9590518426687, 922.8615707643772, 923.8060007398387, 8817.206451419403, 8882.245009174292, 8892.510911899779, 8959.865602015974, 8979.14698103679, 976.9609215631438, 982.4007355513131, 989.9505024748734, 994.3437331361412, 1008.3647302199714, 4859.246816743411, 5339.687011423581, 5363.061464243741, 5414.29614150158, 5468.562514998791, 20079.29722459707, 20631.69052464214, 20960.580788384148, 20973.58052838965, 21876.781232187794, 20312.187512499335, 20548.38354849357, 20966.58066838655, 20984.580308393746, 21223.363299101053, 11091.279066860629, 11340.773184536263, 11412.201145919706, 11417.771644567061, 12023.21849079807, 8804.207621314112, 9268.397554158959, 9272.536373181449, 9412.058794120807, 9554.283428742732, 18783.15475803565, 19117.90441047786, 19609.11758970878, 19719.605607887475, 19916.402507924784, 19817.405477835688, 19982.0, 20132.396028118594, 20191.192352305745, 20366.89816550967, 11513.481893314989, 11795.469203886018, 11807.704807379541, 11820.40897955116, 11844.466998984894, 14400.567982960529, 14443.638909027359, 14443.927780361031, 14564.635884102558, 14604.853951460775, 11505.769884602429, 11555.42222888869, 11584.94207528957, 11628.767424651629, 11783.882161178279, 10875.129989600971, 11092.279001864854, 11139.665810025712, 11188.552457901593, 11299.3785341807, 9195.908040919505, 9413.670521531714, 9418.529073546433, 9421.0, 9422.19911307519, 7452.9627351865065, 7482.438817088631, 7595.468317217742, 7602.087749470099, 7723.884141737766, 7267.818304542217, 7336.413086953137, 7376.832089869206, 7432.2567743225245, 7495.925040749523], "value": 6780.118135745943, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.4315858342201, 499.95250451207124, 528.1971403466729, 530.3847536857244, 534.6417900006995, 6406.327335629756, 6416.45460135889, 6422.839429014265, 6439.388258115471, 6460.2247730272375, 6037.245344331958, 6134.141220229171, 6151.600145990518, 6215.160953271307, 6282.654454005028, 5085.093090091215, 5087.592992560586, 5112.744362781856, 5119.2321151827155, 5124.0, 5027.611884354938, 5064.468230835746, 5067.873303167414, 5080.364954380702, 5098.209777484477, 5083.161278389061, 5242.18746094356, 5271.499207575274, 5288.92066619001, 5307.416184219752, 5237.580993520509, 5264.025825408632, 5306.3632364116165, 5346.5514742990745, 5358.196270559409, 4903.313536104948, 4935.136351138536, 4948.158813001763, 4948.257761335793, 4959.256111583255, 6249.750009999594, 6279.811605651837, 6312.368763123697, 6344.904826427607, 6351.0, 6058.000429929057, 6061.424164704346, 6082.239720034996, 6094.603850749687, 6118.938810611881, 6540.247871494744, 6573.046908198332, 6604.834879128012, 6692.4980626452725, 6759.425448836855, 5283.392409872837, 5312.3890752563175, 5328.973355133199, 5368.32895888014, 5368.62419630626, 6931.792046238621, 7010.544314619533, 7074.009638650593, 7138.964850096759, 7143.24995875436, 5504.366997795225, 5522.392536820947, 5544.445555444423, 5553.333599967949, 5559.221708960748, 5596.377050655346, 5601.691906945157, 5642.435756424325, 5650.321961364581, 5679.8924209779525, 6089.451949324582, 6105.450509454082, 6125.295591007002, 6140.109684095781, 6177.444030037318, 6602.3397660233595, 6661.3005634408655, 6689.598624082473, 6746.0, 6749.966250168718, 1831.8626103042325, 1869.5886904880904, 1909.7326374307609, 1926.364299781069, 1947.3573720672143, 1436.6695659998259, 1440.1143296872558, 1453.2370505484705, 1468.7209430208227, 1474.8820094392422, 5781.508571771363, 5812.9709351452975, 5852.502537284378, 5861.9706901465215, 5957.285125784847, 6480.740770369227, 6546.116274302908, 6571.34286571339, 6707.5975441473665, 6748.865022699617, 5780.653160810365, 5795.82612521625, 5827.213326200994, 5848.912266316009, 5866.837183278687, 3559.982200089035, 3569.8929032129076, 3574.2315402188656, 3593.7125029997537, 3675.4854320395166, 6496.109231391985, 6594.680484282802, 6616.139901812733, 6656.36764507371, 6666.266710661824, 6993.76199616118, 7015.351392422776, 7054.576725396492, 7106.360427561544, 7118.216996130422, 6562.639054852029, 6621.801345959629, 6629.602223866584, 6642.704672588896, 6650.933490665225, 6798.796036118924, 6812.420944219599, 6827.351401616838, 6894.586324820527, 6907.067545881242, 6730.226024007302, 6808.455323574201, 6825.897611535732, 6867.244603093556, 6883.586984780929, 6507.284198738041, 6646.567973081927, 6649.135612370361, 6694.690331835509, 6727.486315578955, 4969.204927211629, 4985.975070124626, 5034.44621091687, 5068.619853511075, 5084.186530155305, 6750.594964302158, 6803.489738269551, 6870.519063665495, 6918.584884906922, 6939.132608423947, 6567.408933196035, 6600.702968366339, 6616.172978377703, 6632.966835165793, 6634.237062737654, 6609.3060228675295, 6636.469082473294, 6688.866222675617, 6722.495812813961, 6756.45948324123, 6298.685065746603, 6488.351164883474, 6513.4463570595135, 6558.934410655832, 6615.4046135848, 6348.619082855043, 6567.934320656732, 6589.110470086477, 6603.0, 6656.0, 6354.0, 6387.520935929917, 6399.456046236122, 6490.318516555693, 6495.1556297681], "value": 5242.070540782688, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [905.5834316214537, 911.9954400228, 925.4077390470098, 930.0327659234397, 935.5976929920132, 19511.804881951175, 20207.69688454674, 20243.696344554806, 20253.898730506364, 22429.43926401841, 20893.791062089374, 20965.580688386224, 21330.253441129564, 21439.57120857582, 21473.785262147372, 926.8285367207088, 950.3822515365033, 952.1383148250809, 953.0183910572122, 953.2897990996718, 945.7919257763281, 980.5489474841546, 989.9703008909709, 994.6469003503761, 995.9800803983916, 1054.2830875005031, 1057.3919996002292, 1058.2063452410691, 1059.4490864750326, 1060.2737125069314, 1044.0968562193723, 1044.858944042556, 1045.8012977534245, 1046.7540128069897, 1047.1308813684623, 774.7598244544209, 774.7869335932608, 774.9070111586645, 775.3564541430655, 776.9689212431496, 20243.797562024338, 20254.189832406686, 20481.79518204814, 20493.07781149852, 20550.0, 21367.786322136733, 21510.89244553783, 21607.0, 21863.453413664785, 21909.671354929687, 17275.654486910193, 17476.475705728848, 17594.736078958827, 17688.292468301253, 17703.5574110647, 3752.6622603965775, 3764.887053388403, 3768.189839184561, 3772.13240954582, 3773.0378753417976, 124811.0, 131231.0, 131774.0, 133243.6675633241, 134545.0, 16043.197840108181, 16228.350865965229, 16329.755053674204, 16649.83350166507, 16832.410865619644, 30822.845885770428, 30869.69130308713, 31044.844775775975, 31310.0, 31371.529427058613, 7544.47188696786, 7809.648565814552, 8508.446950948168, 8576.528290943934, 8848.380613357132, 4161.500619925629, 4222.704410691284, 4241.87274381769, 4242.066745316026, 4254.595813397784, 4964.528369804862, 5030.798768049311, 5044.545990860766, 5058.797648094035, 5082.33929589151, 1054.0, 1075.4891426572315, 1079.875814281352, 1084.766775143348, 1085.5006696919347, 614.1494030767419, 628.924529056507, 637.1939496536885, 649.9415052645285, 652.2303681655692, 11500.827487587692, 11929.582464613697, 12454.875451245553, 13132.868671313356, 13411.664708382174, 678.0845858091537, 678.25053316086, 680.829792551862, 685.3352248319122, 694.4583225084442, 5639.052663135822, 5640.291787372231, 5729.856753581111, 5871.7651293947765, 5896.498797602252, 109943.0, 111049.0, 111553.0, 111556.0, 118333.0, 97443.0, 97640.51179744056, 98875.0, 102204.48897755612, 103192.0, 82093.0, 83100.0, 83457.58271208605, 85622.0, 87050.1294987042, 10567.577296908037, 10706.250562460553, 10995.780084398428, 12885.549005784753, 12897.742045158857, 40496.595034050464, 41221.0, 41472.0, 42042.7897860521, 42195.57804421917, 68534.31465685279, 70925.0, 71209.0, 73456.26543734495, 74994.62502687452, 15487.767683484532, 17383.304667813147, 17762.91118544399, 18287.359942401952, 19116.522086947938, 19733.112009959874, 19761.308354207533, 20997.160113595895, 21601.027953742432, 21848.344549663536, 18186.45440636783, 19918.103685333906, 19936.3022294219, 19953.60092798165, 20084.297049603192, 15157.696846063238, 18800.811991879906, 19309.227630894606, 19582.216711331388, 19755.209791608173, 18286.08569571543, 18392.264309428007, 18490.537736556693, 18631.44105676832, 18669.533261668566, 12561.371931403575, 12756.681082973, 12867.549635762703, 13330.133541319783, 13376.799348009594, 14034.157950523002, 14439.0, 14918.77621835695, 14973.925130374279, 15162.0], "value": 9236.369816215449, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1490.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 106.0, 100.0, 107.0, 114.0, 108.0, 107.0, 98.0, 111.0, 107.0], "value": 105.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 114.0, 143.0, 167.0, 127.0, 142.0, 142.0, 114.0, 168.0, 118.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 162.0, 163.0, 162.0, 162.0, 165.0, 165.0, 172.0, 163.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 169.0, 75.0, 77.0, 75.0, 73.0, 169.0, 73.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 145.0, 80.0, 80.0, 81.0, 132.0, 79.0, 79.0, 124.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 156.0, 149.0, 151.0, 149.0, 148.0, 149.0, 148.0, 150.0], "value": 149.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 98.0, 93.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 55.0, 53.0, 60.0, 54.0, 53.0, 53.0, 54.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 39.0, 38.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 78.0, 75.0, 75.0, 75.0, 75.0, 75.0, 77.0, 75.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 121.0, 131.0, 130.0, 115.0, 124.0, 122.0, 118.0, 118.0, 158.0], "value": 126.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 171.0, 175.0, 176.0, 174.0, 169.0, 171.0, 167.0, 182.0, 178.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 65.0, 65.0, 64.0, 68.0, 64.0, 65.0, 68.0, 64.0], "value": 65.6, "unit": "score"}]}]} 16:06:36 INFO - Return code: 0 16:06:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:06:36 INFO - # TBPL SUCCESS # 16:06:36 INFO - Running post-action listener: _package_coverage_data 16:06:36 INFO - Running post-action listener: _resource_record_post_action 16:06:36 INFO - [mozharness: 2017-09-05 23:06:36.924903Z] Finished run-tests step (success) 16:06:36 INFO - Running post-run listener: _resource_record_post_run 16:06:37 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 395272192; Read time: 12; Write time: 257152 16:06:37 INFO - TinderboxPrint: CPU usage
13.1% 16:06:37 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:06:37 INFO - TinderboxPrint: I/O write bytes / time
395,272,192 / 257,152 16:06:37 INFO - TinderboxPrint: CPU idle
8,237.4 (86.9%) 16:06:37 INFO - TinderboxPrint: CPU user
1,192.7 (12.6%) 16:06:37 INFO - TinderboxPrint: Swap in / out
0 / 0 16:06:37 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105259008; Read time: 0; Write time: 85260 16:06:37 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:06:37 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290013184; Read time: 12; Write time: 171892 16:06:37 INFO - Running post-run listener: _upload_blobber_files 16:06:37 INFO - Blob upload gear active. 16:06:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:06:37 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:06:37 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:06:37 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:06:37 INFO - (blobuploader) - INFO - Open directory for files ... 16:06:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:06:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:06:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:39 INFO - (blobuploader) - INFO - Done attempting. 16:06:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:06:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:06:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:39 INFO - (blobuploader) - INFO - Done attempting. 16:06:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:06:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:06:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:40 INFO - (blobuploader) - INFO - Done attempting. 16:06:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:06:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:06:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:06:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:06:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:06:44 INFO - (blobuploader) - INFO - Done attempting. 16:06:44 INFO - (blobuploader) - INFO - Iteration through files over. 16:06:44 INFO - Return code: 0 16:06:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:06:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:06:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9c3ea04000824e88f6900ddf0f0875a304eb383e5fa7bb947882be9460ad574cf80dbf0a62778e359bfd0012ea7045f6b55386b262183c0cda13040661f8e54", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85700e728242fe0f4ddb74b2c9dc1e107e9cec34d9e06e5a58103d46e2d9dfec9804f440b578317681021ca3c59c066d308a1c14b556029e598c425af2a16b0e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5a4cef9735d61a4e206e1f5958207350768d71c5a1619790a083aaf35d91e7d10a6e1df9f6982803dbbe0a5921116609b86397c6c3946703a2e082542f678c3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98e613cad07d4418c8a9fca5ed076293a0f1ec5caa3c99bbfcfc19d60f0f7ead8d253bf6300aacd4a7d6d0f14bb1ce4652d6dca465c902aadefc74f3115caea0"} 16:06:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:06:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:06:44 INFO - Contents: 16:06:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9c3ea04000824e88f6900ddf0f0875a304eb383e5fa7bb947882be9460ad574cf80dbf0a62778e359bfd0012ea7045f6b55386b262183c0cda13040661f8e54", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85700e728242fe0f4ddb74b2c9dc1e107e9cec34d9e06e5a58103d46e2d9dfec9804f440b578317681021ca3c59c066d308a1c14b556029e598c425af2a16b0e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5a4cef9735d61a4e206e1f5958207350768d71c5a1619790a083aaf35d91e7d10a6e1df9f6982803dbbe0a5921116609b86397c6c3946703a2e082542f678c3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98e613cad07d4418c8a9fca5ed076293a0f1ec5caa3c99bbfcfc19d60f0f7ead8d253bf6300aacd4a7d6d0f14bb1ce4652d6dca465c902aadefc74f3115caea0"} 16:06:44 INFO - Running post-run listener: copy_logs_to_upload_dir 16:06:44 INFO - Copying logs to upload dir... 16:06:44 INFO - mkdir: /builds/slave/test/build/upload/logs 16:06:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.683657 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-09-05 16:06:44.241140) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:06:44.248543) ========= 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=0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617 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/d9c3ea04000824e88f6900ddf0f0875a304eb383e5fa7bb947882be9460ad574cf80dbf0a62778e359bfd0012ea7045f6b55386b262183c0cda13040661f8e54", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85700e728242fe0f4ddb74b2c9dc1e107e9cec34d9e06e5a58103d46e2d9dfec9804f440b578317681021ca3c59c066d308a1c14b556029e598c425af2a16b0e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5a4cef9735d61a4e206e1f5958207350768d71c5a1619790a083aaf35d91e7d10a6e1df9f6982803dbbe0a5921116609b86397c6c3946703a2e082542f678c3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98e613cad07d4418c8a9fca5ed076293a0f1ec5caa3c99bbfcfc19d60f0f7ead8d253bf6300aacd4a7d6d0f14bb1ce4652d6dca465c902aadefc74f3115caea0"} build_url:https://queue.taskcluster.net/v1/task/ciQLVfAyTVS5ODxEPFePfg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011368 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/d9c3ea04000824e88f6900ddf0f0875a304eb383e5fa7bb947882be9460ad574cf80dbf0a62778e359bfd0012ea7045f6b55386b262183c0cda13040661f8e54", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/85700e728242fe0f4ddb74b2c9dc1e107e9cec34d9e06e5a58103d46e2d9dfec9804f440b578317681021ca3c59c066d308a1c14b556029e598c425af2a16b0e", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d5a4cef9735d61a4e206e1f5958207350768d71c5a1619790a083aaf35d91e7d10a6e1df9f6982803dbbe0a5921116609b86397c6c3946703a2e082542f678c3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98e613cad07d4418c8a9fca5ed076293a0f1ec5caa3c99bbfcfc19d60f0f7ead8d253bf6300aacd4a7d6d0f14bb1ce4652d6dca465c902aadefc74f3115caea0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 16:06:44.279755) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:06:44.280182) ========= 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=0333a256a5e4f052382dd180000001b1-1504651555.587599-2046068617 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004576 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 16:06:44.361843) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:06:44.365159) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 16:06:44.365458) ========= ========= Total master_lag: 0.24 =========