builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-021 starttime: 1506191175.28 results: success (0) revision: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:15.283779) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:15.284195) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:15.284473) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:15.410254) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:15.410547) ========= 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=df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 11:26:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 118M=0s 2017-09-23 11:26:15 (118 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.199515 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:15.656642) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:15.660047) ========= 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=df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016787 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:15.710569) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:15.710889) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 --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 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 --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=df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 11:26:15,767 truncating revision to first 12 chars 2017-09-23 11:26:15,767 Setting DEBUG logging. 2017-09-23 11:26:15,767 attempt 1/10 2017-09-23 11:26:15,767 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2d9d0bebb5c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 11:26:16,289 unpacking tar archive at: mozilla-central-2d9d0bebb5c6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.865550 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:16.595622) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:16.598963) ========= script_repo_revision: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:16.599423) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:16.599688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 11:26:16.613247) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-09-23 11:26:16.613559) ========= /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=df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:26:16 INFO - MultiFileLogger online at 20170923 11:26:16 in /builds/slave/test 11:26:16 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 11:26:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:26:16 INFO - {'append_to_log': False, 11:26:16 INFO - 'base_work_dir': '/builds/slave/test', 11:26:16 INFO - 'blob_upload_branch': 'Firefox', 11:26:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:26:16 INFO - 'branch': 'Firefox', 11:26:16 INFO - 'buildbot_json_path': 'buildprops.json', 11:26:16 INFO - 'code_coverage': False, 11:26:16 INFO - 'config_files': ('talos/linux_config.py',), 11:26:16 INFO - 'default_actions': ('clobber', 11:26:16 INFO - 'read-buildbot-config', 11:26:16 INFO - 'download-and-extract', 11:26:16 INFO - 'populate-webroot', 11:26:16 INFO - 'create-virtualenv', 11:26:16 INFO - 'install', 11:26:16 INFO - 'setup-mitmproxy', 11:26:16 INFO - 'run-tests'), 11:26:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:26:16 INFO - 'disable_ccov_upload': False, 11:26:16 INFO - 'disable_stylo': False, 11:26:16 INFO - 'download_minidump_stackwalk': True, 11:26:16 INFO - 'download_symbols': 'ondemand', 11:26:16 INFO - 'enable_stylo': False, 11:26:16 INFO - 'enable_webrender': False, 11:26:16 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:26:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:26:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:26:16 INFO - 'gecko_profile': False, 11:26:16 INFO - 'gecko_profile_interval': 0, 11:26:16 INFO - 'installer_path': 'installer.exe', 11:26:16 INFO - 'log_level': 'info', 11:26:16 INFO - 'log_name': 'talos', 11:26:16 INFO - 'log_to_console': True, 11:26:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:26:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:26:16 INFO - 'opt_config_files': (), 11:26:16 INFO - 'pip_index': False, 11:26:16 INFO - 'suite': 'dromaeojs-e10s', 11:26:16 INFO - 'system_bits': '32', 11:26:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:26:16 INFO - 'title': 'talos-linux64-ix-021', 11:26:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:26:16 INFO - 'use_talos_json': True, 11:26:16 INFO - 'verify': 'False', 11:26:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:26:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:26:16 INFO - 'work_dir': 'build'} 11:26:16 INFO - [mozharness: 2017-09-23 18:26:16.881847Z] Running clobber step. 11:26:16 INFO - Running pre-action listener: _resource_record_pre_action 11:26:16 INFO - Running main action method: clobber 11:26:16 INFO - rmtree: /builds/slave/test/build 11:26:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:26:17 INFO - Running post-action listener: _resource_record_post_action 11:26:17 INFO - [mozharness: 2017-09-23 18:26:17.845589Z] Finished clobber step (success) 11:26:17 INFO - [mozharness: 2017-09-23 18:26:17.845686Z] Running read-buildbot-config step. 11:26:17 INFO - Running pre-action listener: _resource_record_pre_action 11:26:17 INFO - Running main action method: read_buildbot_config 11:26:17 INFO - Using buildbot properties: 11:26:17 INFO - { 11:26:17 INFO - "project": "", 11:26:17 INFO - "product": "firefox", 11:26:17 INFO - "who": "ffxbld@noreply.mozilla.org", 11:26:17 INFO - "installer_path": "public/build/target.tar.bz2", 11:26:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 11:26:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:26:17 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 11:26:17 INFO - "stage_platform": "linux64-qr", 11:26:17 INFO - "basedir": "/builds/slave/test", 11:26:17 INFO - "buildnumber": 91, 11:26:17 INFO - "platform": "ubuntu64_hw_qr", 11:26:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:26:17 INFO - "slavebuilddir": "test", 11:26:17 INFO - "taskId": "AXaxWgnNRXytZyw-sTAzCA", 11:26:17 INFO - "branch": "mozilla-central", 11:26:17 INFO - "script_repo_revision": "production", 11:26:17 INFO - "revision": "2d9d0bebb5c66ba3dfdffd713a888a8c49730631", 11:26:17 INFO - "slavename": "talos-linux64-ix-021", 11:26:17 INFO - "repo_path": "mozilla-central" 11:26:17 INFO - } 11:26:17 INFO - Finding installer, test and symbols from parent task. 11:26:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AXaxWgnNRXytZyw-sTAzCA'}, attempt #1 11:26:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AXaxWgnNRXytZyw-sTAzCA'}, attempt #1 11:26:18 INFO - Task dependencies: DNUXLEF0TY6Rkrc0rZZnLg 11:26:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg'}, attempt #1 11:26:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 11:26:19 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.test_packages.json 11:26:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.crashreporter-symbols.zip 11:26:19 INFO - Running post-action listener: _resource_record_post_action 11:26:19 INFO - [mozharness: 2017-09-23 18:26:19.205532Z] Finished read-buildbot-config step (success) 11:26:19 INFO - [mozharness: 2017-09-23 18:26:19.205750Z] Running download-and-extract step. 11:26:19 INFO - Running pre-action listener: _resource_record_pre_action 11:26:19 INFO - Running main action method: download_and_extract 11:26:19 INFO - mkdir: /builds/slave/test/build/tests 11:26:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:19 INFO - trying https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.test_packages.json 11:26:19 INFO - Downloading https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:26:19 INFO - Downloaded 989 bytes. 11:26:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:26:19 INFO - Using the following test package requirements: 11:26:19 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:26:19 INFO - u'common': [u'target.common.tests.zip'], 11:26:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:26:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:26:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:26:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:26:19 INFO - u'mozbase': [u'target.common.tests.zip'], 11:26:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:26:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:26:19 INFO - u'web-platform': [u'target.common.tests.zip', 11:26:19 INFO - u'target.web-platform.tests.tar.gz'], 11:26:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:26:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:26:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.common.tests.zip 11:26:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:26:19 INFO - Fetch https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.common.tests.zip into memory 11:26:21 INFO - Content-Length response header: 54943881 11:26:21 INFO - Bytes received: 54943881 11:26:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.talos.tests.zip 11:26:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:26:33 INFO - Fetch https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.talos.tests.zip into memory 11:26:33 INFO - Content-Length response header: 14513823 11:26:33 INFO - Bytes received: 14513823 11:26: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')]} 11:26:34 INFO - trying https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 11:26:34 INFO - Downloading https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:26:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:26:38 INFO - Downloaded 61524879 bytes. 11:26:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 11:26:38 INFO - mkdir: /builds/slave/test/properties 11:26:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:26:38 INFO - Writing to file /builds/slave/test/properties/build_url 11:26:38 INFO - Contents: 11:26:38 INFO - build_url:https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 11:26:38 INFO - Running post-action listener: _resource_record_post_action 11:26:38 INFO - Running post-action listener: find_tests_for_verification 11:26:38 INFO - Running post-action listener: set_extra_try_arguments 11:26:38 INFO - [mozharness: 2017-09-23 18:26:38.517744Z] Finished download-and-extract step (success) 11:26:38 INFO - [mozharness: 2017-09-23 18:26:38.517852Z] Running populate-webroot step. 11:26:38 INFO - Running pre-action listener: _resource_record_pre_action 11:26:38 INFO - Running main action method: populate_webroot 11:26:38 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:26:38 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['tresize', 11:26:38 INFO - 'tcanvasmark']}, 11:26:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:26:38 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['dromaeo_css', 11:26:38 INFO - 'kraken']}, 11:26:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:38 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:26:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'tests': ['damp', 'tps']}, 11:26:38 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['damp', 'tps']}, 11:26:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:26:38 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['dromaeo_dom']}, 11:26:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:26:38 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['basic_compositor_video', 11:26:38 INFO - 'glvideo']}, 11:26:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:26:38 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['ts_paint_webext', 11:26:38 INFO - 'tp5o_webext']}, 11:26:38 INFO - 'other-e10s': {'tests': ['a11yr', 11:26:38 INFO - 'ts_paint', 11:26:38 INFO - 'tpaint', 11:26:38 INFO - 'sessionrestore', 11:26:38 INFO - 'sessionrestore_many_windows', 11:26:38 INFO - 'sessionrestore_no_auto_restore', 11:26:38 INFO - 'tabpaint']}, 11:26:38 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['a11yr', 11:26:38 INFO - 'ts_paint', 11:26:38 INFO - 'tpaint', 11:26:38 INFO - 'sessionrestore', 11:26:38 INFO - 'sessionrestore_many_windows', 11:26:38 INFO - 'sessionrestore_no_auto_restore', 11:26:38 INFO - 'tabpaint']}, 11:26:38 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 11:26:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:26:38 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['perf_reftest_singletons']}, 11:26:38 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['perf_reftest']}, 11:26:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:26:38 INFO - 'tsvgr_opacity', 11:26:38 INFO - 'tart', 11:26:38 INFO - 'tscrollx', 11:26:38 INFO - 'tsvg_static']}, 11:26:38 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['tsvgx', 11:26:38 INFO - 'tsvgr_opacity', 11:26:38 INFO - 'tart', 11:26:38 INFO - 'tscrollx', 11:26:38 INFO - 'tsvg_static']}, 11:26:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:26:38 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo'], 11:26:38 INFO - 'tests': ['tp5o']}, 11:26:38 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:26:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:26:38 INFO - 'talos_options': ['--mitmproxy', 11:26:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:26:38 INFO - '--firstNonBlankPaint'], 11:26:38 INFO - 'tests': ['tp6_google', 11:26:38 INFO - 'tp6_youtube', 11:26:38 INFO - 'tp6_amazon', 11:26:38 INFO - 'tp6_facebook']}, 11:26:38 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:26:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:26:38 INFO - 'talos_options': ['--disable-stylo', 11:26:38 INFO - '--mitmproxy', 11:26:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:26:38 INFO - '--firstNonBlankPaint'], 11:26:38 INFO - 'tests': ['tp6_google', 11:26:38 INFO - 'tp6_youtube', 11:26:38 INFO - 'tp6_amazon', 11:26:38 INFO - 'tp6_facebook']}, 11:26:38 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:26:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:26:38 INFO - 'talos_options': ['--stylo-threads=1', 11:26:38 INFO - '--mitmproxy', 11:26:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:26:38 INFO - '--firstNonBlankPaint'], 11:26:38 INFO - 'tests': ['tp6_google', 11:26:38 INFO - 'tp6_youtube', 11:26:38 INFO - 'tp6_amazon', 11:26:38 INFO - 'tp6_facebook']}, 11:26:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--xperf_path', 11:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:38 INFO - 'tests': ['tp5n']}, 11:26:38 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:26:38 INFO - 'talos_options': ['--disable-stylo', 11:26:38 INFO - '--xperf_path', 11:26:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:26:38 INFO - 'tests': ['tp5n']}}} 11:26:38 INFO - Running post-action listener: _resource_record_post_action 11:26:38 INFO - [mozharness: 2017-09-23 18:26:38.533641Z] Finished populate-webroot step (success) 11:26:38 INFO - [mozharness: 2017-09-23 18:26:38.533729Z] Running create-virtualenv step. 11:26:38 INFO - Running pre-action listener: _resource_record_pre_action 11:26:38 INFO - Running main action method: create_virtualenv 11:26:38 INFO - Creating virtualenv /builds/slave/test/build/venv 11:26:38 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:26:38 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:26:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:26:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:26:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:26:41 INFO - Installing distribute.............................................................................................................................................................................................done. 11:26:41 INFO - Installing pip...............done. 11:26:41 INFO - Return code: 0 11:26:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:26:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:26:42 INFO - Reading from file tmpfile_stdout 11:26:42 INFO - Output received: 11:26:42 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:26:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:26: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')]} 11:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26: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')]} 11:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:42 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 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:26:42 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 11:26:42 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 11:26:42 INFO - Using env: {'DISPLAY': ':0', 11:26:42 INFO - 'HOME': '/home/cltbld', 11:26:42 INFO - 'LANG': 'en_US.UTF-8', 11:26:42 INFO - 'LANGUAGE': 'en_US:en', 11:26:42 INFO - 'LOGNAME': 'cltbld', 11:26:42 INFO - 'MAIL': '/var/mail/cltbld', 11:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:42 INFO - 'MOZ_NO_REMOTE': '1', 11:26:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:42 INFO - 'NO_EM_RESTART': '1', 11:26:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:42 INFO - 'PWD': '/builds/slave/test', 11:26:42 INFO - 'SHELL': '/bin/bash', 11:26:42 INFO - 'SHLVL': '1', 11:26:42 INFO - 'TERM': 'linux', 11:26:42 INFO - 'TMOUT': '86400', 11:26:42 INFO - 'USER': 'cltbld', 11:26:42 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:42 INFO - '_': '/tools/buildbot/bin/python'} 11:26:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:26:43 INFO - Downloading/unpacking pip>=1.5 11:26:43 INFO - Running setup.py egg_info for package pip 11:26:43 INFO - warning: no files found matching 'pip/cacert.pem' 11:26:43 INFO - warning: no files found matching '*.html' under directory 'docs' 11:26:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:26:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:26:43 INFO - Installing collected packages: pip 11:26:43 INFO - Found existing installation: pip 1.1 11:26:43 INFO - Uninstalling pip: 11:26:43 INFO - Successfully uninstalled pip 11:26:43 INFO - Running setup.py install for pip 11:26:43 INFO - warning: no files found matching 'pip/cacert.pem' 11:26:43 INFO - warning: no files found matching '*.html' under directory 'docs' 11:26:43 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:26:43 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:26:43 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:26:43 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:26:43 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:26:43 INFO - Successfully installed pip 11:26:43 INFO - Cleaning up... 11:26:43 INFO - Return code: 0 11:26:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:43 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 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:26:43 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 11:26:43 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 11:26:43 INFO - Using env: {'DISPLAY': ':0', 11:26:43 INFO - 'HOME': '/home/cltbld', 11:26:43 INFO - 'LANG': 'en_US.UTF-8', 11:26:43 INFO - 'LANGUAGE': 'en_US:en', 11:26:43 INFO - 'LOGNAME': 'cltbld', 11:26:43 INFO - 'MAIL': '/var/mail/cltbld', 11:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:43 INFO - 'MOZ_NO_REMOTE': '1', 11:26:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:43 INFO - 'NO_EM_RESTART': '1', 11:26:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:43 INFO - 'PWD': '/builds/slave/test', 11:26:43 INFO - 'SHELL': '/bin/bash', 11:26:43 INFO - 'SHLVL': '1', 11:26:43 INFO - 'TERM': 'linux', 11:26:43 INFO - 'TMOUT': '86400', 11:26:43 INFO - 'USER': 'cltbld', 11:26:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:43 INFO - '_': '/tools/buildbot/bin/python'} 11:26:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:44 INFO - Downloading/unpacking psutil>=3.1.1 11:26:44 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 11:26:44 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 11:26:44 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 11:26:44 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 11:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:26:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:47 INFO - Installing collected packages: psutil 11:26:47 INFO - Running setup.py install for psutil 11:26:47 INFO - building 'psutil._psutil_linux' extension 11:26:47 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 11:26:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:26:49 INFO - building 'psutil._psutil_posix' extension 11:26:49 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 11:26:49 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 11:26:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:26:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:26:49 INFO - Successfully installed psutil 11:26:49 INFO - Cleaning up... 11:26:49 INFO - Return code: 0 11:26:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:26:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:26:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:26:49 INFO - Using env: {'DISPLAY': ':0', 11:26:49 INFO - 'HOME': '/home/cltbld', 11:26:49 INFO - 'LANG': 'en_US.UTF-8', 11:26:49 INFO - 'LANGUAGE': 'en_US:en', 11:26:49 INFO - 'LOGNAME': 'cltbld', 11:26:49 INFO - 'MAIL': '/var/mail/cltbld', 11:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:49 INFO - 'MOZ_NO_REMOTE': '1', 11:26:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:49 INFO - 'NO_EM_RESTART': '1', 11:26:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:49 INFO - 'PWD': '/builds/slave/test', 11:26:49 INFO - 'SHELL': '/bin/bash', 11:26:49 INFO - 'SHLVL': '1', 11:26:49 INFO - 'TERM': 'linux', 11:26:49 INFO - 'TMOUT': '86400', 11:26:49 INFO - 'USER': 'cltbld', 11:26:49 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:49 INFO - '_': '/tools/buildbot/bin/python'} 11:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:26:49 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 11:26:49 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 11:26:49 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 11:26:49 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 11:26:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:26:50 INFO - Installing collected packages: mozsystemmonitor 11:26:50 INFO - Running setup.py install for mozsystemmonitor 11:26:50 INFO - Successfully installed mozsystemmonitor 11:26:50 INFO - Cleaning up... 11:26:50 INFO - Return code: 0 11:26:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:26:50 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')]} 11:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:50 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')]} 11:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:50 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 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:26:50 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 11:26:50 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 11:26:50 INFO - Using env: {'DISPLAY': ':0', 11:26:50 INFO - 'HOME': '/home/cltbld', 11:26:50 INFO - 'LANG': 'en_US.UTF-8', 11:26:50 INFO - 'LANGUAGE': 'en_US:en', 11:26:50 INFO - 'LOGNAME': 'cltbld', 11:26:50 INFO - 'MAIL': '/var/mail/cltbld', 11:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:50 INFO - 'MOZ_NO_REMOTE': '1', 11:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:50 INFO - 'NO_EM_RESTART': '1', 11:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:50 INFO - 'PWD': '/builds/slave/test', 11:26:50 INFO - 'SHELL': '/bin/bash', 11:26:50 INFO - 'SHLVL': '1', 11:26:50 INFO - 'TERM': 'linux', 11:26:50 INFO - 'TMOUT': '86400', 11:26:50 INFO - 'USER': 'cltbld', 11:26:50 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:50 INFO - '_': '/tools/buildbot/bin/python'} 11:26:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:51 INFO - Downloading/unpacking jsonschema==2.5.1 11:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:26:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:52 INFO - Downloading functools32-3.2.3-2.tar.gz 11:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:26:52 INFO - warning: no files found matching '*.txt' 11:26:52 INFO - no previously-included directories found matching 'build' 11:26:52 INFO - no previously-included directories found matching 'dist' 11:26:52 INFO - no previously-included directories found matching '.git*' 11:26:52 INFO - Installing collected packages: jsonschema, functools32 11:26:52 INFO - Running setup.py install for functools32 11:26:52 INFO - warning: no files found matching '*.txt' 11:26:52 INFO - no previously-included directories found matching 'build' 11:26:52 INFO - no previously-included directories found matching 'dist' 11:26:52 INFO - no previously-included directories found matching '.git*' 11:26:52 INFO - Successfully installed jsonschema functools32 11:26:52 INFO - Cleaning up... 11:26:52 INFO - Return code: 0 11:26:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:26:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:26:52 INFO - Using env: {'DISPLAY': ':0', 11:26:52 INFO - 'HOME': '/home/cltbld', 11:26:52 INFO - 'LANG': 'en_US.UTF-8', 11:26:52 INFO - 'LANGUAGE': 'en_US:en', 11:26:52 INFO - 'LOGNAME': 'cltbld', 11:26:52 INFO - 'MAIL': '/var/mail/cltbld', 11:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:52 INFO - 'MOZ_NO_REMOTE': '1', 11:26:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:52 INFO - 'NO_EM_RESTART': '1', 11:26:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:52 INFO - 'PWD': '/builds/slave/test', 11:26:52 INFO - 'SHELL': '/bin/bash', 11:26:52 INFO - 'SHLVL': '1', 11:26:52 INFO - 'TERM': 'linux', 11:26:52 INFO - 'TMOUT': '86400', 11:26:52 INFO - 'USER': 'cltbld', 11:26:52 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:52 INFO - '_': '/tools/buildbot/bin/python'} 11:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:26:52 INFO - Cleaning up... 11:26:52 INFO - Return code: 0 11:26:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:26:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:26:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:26:52 INFO - Using env: {'DISPLAY': ':0', 11:26:52 INFO - 'HOME': '/home/cltbld', 11:26:52 INFO - 'LANG': 'en_US.UTF-8', 11:26:52 INFO - 'LANGUAGE': 'en_US:en', 11:26:52 INFO - 'LOGNAME': 'cltbld', 11:26:52 INFO - 'MAIL': '/var/mail/cltbld', 11:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:52 INFO - 'MOZ_NO_REMOTE': '1', 11:26:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:52 INFO - 'NO_EM_RESTART': '1', 11:26:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:52 INFO - 'PWD': '/builds/slave/test', 11:26:52 INFO - 'SHELL': '/bin/bash', 11:26:52 INFO - 'SHLVL': '1', 11:26:52 INFO - 'TERM': 'linux', 11:26:52 INFO - 'TMOUT': '86400', 11:26:52 INFO - 'USER': 'cltbld', 11:26:52 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:52 INFO - '_': '/tools/buildbot/bin/python'} 11:26:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:53 INFO - Downloading/unpacking blobuploader==1.2.4 11:26:53 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 11:26:53 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 11:26:53 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 11:26:53 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 11:26:54 INFO - Downloading blobuploader-1.2.4.tar.gz 11:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:26:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:26:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:54 INFO - Downloading docopt-0.6.1.tar.gz 11:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:26:54 INFO - Installing collected packages: blobuploader, requests, docopt 11:26:54 INFO - Running setup.py install for blobuploader 11:26:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:26:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:26:54 INFO - Running setup.py install for requests 11:26:55 INFO - Running setup.py install for docopt 11:26:55 INFO - Successfully installed blobuploader requests docopt 11:26:55 INFO - Cleaning up... 11:26:55 INFO - Return code: 0 11:26:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:55 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 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:26:55 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 11:26:55 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 11:26:55 INFO - Using env: {'DISPLAY': ':0', 11:26:55 INFO - 'HOME': '/home/cltbld', 11:26:55 INFO - 'LANG': 'en_US.UTF-8', 11:26:55 INFO - 'LANGUAGE': 'en_US:en', 11:26:55 INFO - 'LOGNAME': 'cltbld', 11:26:55 INFO - 'MAIL': '/var/mail/cltbld', 11:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:55 INFO - 'MOZ_NO_REMOTE': '1', 11:26:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:55 INFO - 'NO_EM_RESTART': '1', 11:26:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:55 INFO - 'PWD': '/builds/slave/test', 11:26:55 INFO - 'SHELL': '/bin/bash', 11:26:55 INFO - 'SHLVL': '1', 11:26:55 INFO - 'TERM': 'linux', 11:26:55 INFO - 'TMOUT': '86400', 11:26:55 INFO - 'USER': 'cltbld', 11:26:55 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:55 INFO - '_': '/tools/buildbot/bin/python'} 11:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:55 INFO - Running setup.py (path:/tmp/pip-p7UX2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:55 INFO - Running setup.py (path:/tmp/pip-iJBZK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:55 INFO - Running setup.py (path:/tmp/pip-BWt8yT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:55 INFO - Running setup.py (path:/tmp/pip-DKgrne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:55 INFO - Running setup.py (path:/tmp/pip-Q2xROv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:55 INFO - Running setup.py (path:/tmp/pip-7iuNSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:56 INFO - Running setup.py (path:/tmp/pip-MEZfKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:56 INFO - Running setup.py (path:/tmp/pip-QwXRTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:56 INFO - Running setup.py (path:/tmp/pip-1V8Fno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:56 INFO - Running setup.py (path:/tmp/pip-pL5oAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:56 INFO - Running setup.py (path:/tmp/pip-JJaKeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:56 INFO - Running setup.py (path:/tmp/pip-xS4mBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:56 INFO - Running setup.py (path:/tmp/pip-ymCHT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:56 INFO - Running setup.py (path:/tmp/pip-soQFuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:56 INFO - Running setup.py (path:/tmp/pip-F6ckW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:56 INFO - Running setup.py (path:/tmp/pip-zt9a72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:56 INFO - Running setup.py (path:/tmp/pip-D1oigs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:26:56 INFO - Running setup.py install for manifestparser 11:26:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:26:56 INFO - Running setup.py install for mozcrash 11:26:57 INFO - Running setup.py install for mozdebug 11:26:57 INFO - Running setup.py install for mozdevice 11:26:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:26:57 INFO - Running setup.py install for mozfile 11:26:57 INFO - Running setup.py install for mozhttpd 11:26:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:26:57 INFO - Running setup.py install for mozinfo 11:26:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:26:57 INFO - Running setup.py install for mozInstall 11:26:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:26:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:26:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:26:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:26:57 INFO - Running setup.py install for mozleak 11:26:57 INFO - Running setup.py install for mozlog 11:26:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:26:57 INFO - Running setup.py install for moznetwork 11:26:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:26:57 INFO - Running setup.py install for mozprocess 11:26:58 INFO - Running setup.py install for mozprofile 11:26:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:26:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:26:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:26:58 INFO - Running setup.py install for mozrunner 11:26:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:26:58 INFO - Running setup.py install for mozscreenshot 11:26:58 INFO - Running setup.py install for moztest 11:26:58 INFO - Running setup.py install for mozversion 11:26:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:26:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:26:58 INFO - Cleaning up... 11:26:58 INFO - Return code: 0 11:26:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:58 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')]} 11:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:58 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')]} 11:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:58 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 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:26:58 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 11:26:58 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 11:26:58 INFO - Using env: {'DISPLAY': ':0', 11:26:58 INFO - 'HOME': '/home/cltbld', 11:26:58 INFO - 'LANG': 'en_US.UTF-8', 11:26:58 INFO - 'LANGUAGE': 'en_US:en', 11:26:58 INFO - 'LOGNAME': 'cltbld', 11:26:58 INFO - 'MAIL': '/var/mail/cltbld', 11:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:58 INFO - 'MOZ_NO_REMOTE': '1', 11:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:58 INFO - 'NO_EM_RESTART': '1', 11:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:58 INFO - 'PWD': '/builds/slave/test', 11:26:58 INFO - 'SHELL': '/bin/bash', 11:26:58 INFO - 'SHLVL': '1', 11:26:58 INFO - 'TERM': 'linux', 11:26:58 INFO - 'TMOUT': '86400', 11:26:58 INFO - 'USER': 'cltbld', 11:26:58 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:58 INFO - '_': '/tools/buildbot/bin/python'} 11:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:58 INFO - Running setup.py (path:/tmp/pip-gSwcV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:58 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)) 11:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:58 INFO - Running setup.py (path:/tmp/pip-SFUpur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:58 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)) 11:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:58 INFO - Running setup.py (path:/tmp/pip-UxDv9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:58 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:59 INFO - Running setup.py (path:/tmp/pip-HBAo_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:59 INFO - Running setup.py (path:/tmp/pip-YAD94n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:59 INFO - Running setup.py (path:/tmp/pip-BDUuET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:59 INFO - Running setup.py (path:/tmp/pip-MJmSQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:59 INFO - Running setup.py (path:/tmp/pip-fU6Ixm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:59 INFO - Running setup.py (path:/tmp/pip-1EFzK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:59 INFO - Running setup.py (path:/tmp/pip-m89To9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:59 INFO - Running setup.py (path:/tmp/pip-HgmZDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:59 INFO - Running setup.py (path:/tmp/pip-HGcvtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:59 INFO - Running setup.py (path:/tmp/pip-5rzU7y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:59 INFO - Running setup.py (path:/tmp/pip-I3ilWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:59 INFO - Running setup.py (path:/tmp/pip-6b0TtJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:59 INFO - Running setup.py (path:/tmp/pip-bBRLeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:59 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)) 11:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:59 INFO - Running setup.py (path:/tmp/pip-TCY9mX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:27:00 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:27:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:27:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:27:01 INFO - Downloading blessings-1.6.tar.gz 11:27:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:27:01 INFO - Installing collected packages: blessings 11:27:01 INFO - Running setup.py install for blessings 11:27:01 INFO - Successfully installed blessings 11:27:01 INFO - Cleaning up... 11:27:01 INFO - Return code: 0 11:27:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:27:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:27:01 INFO - Reading from file tmpfile_stdout 11:27:01 INFO - Current package versions: 11:27:01 INFO - argparse == 1.2.1 11:27:01 INFO - blessings == 1.6 11:27:01 INFO - blobuploader == 1.2.4 11:27:01 INFO - docopt == 0.6.1 11:27:01 INFO - functools32 == 3.2.3-2 11:27:01 INFO - jsonschema == 2.5.1 11:27:01 INFO - manifestparser == 1.1 11:27:01 INFO - mozInstall == 1.13 11:27:01 INFO - mozcrash == 1.0 11:27:01 INFO - mozdebug == 0.1 11:27:01 INFO - mozdevice == 0.51 11:27:01 INFO - mozfile == 1.2 11:27:01 INFO - mozhttpd == 0.7 11:27:01 INFO - mozinfo == 0.10 11:27:01 INFO - mozleak == 0.1 11:27:01 INFO - mozlog == 3.5 11:27:01 INFO - moznetwork == 0.27 11:27:01 INFO - mozprocess == 0.25 11:27:01 INFO - mozprofile == 0.28 11:27:01 INFO - mozrunner == 6.13 11:27:01 INFO - mozscreenshot == 0.1 11:27:01 INFO - mozsystemmonitor == 0.3 11:27:01 INFO - moztest == 0.8 11:27:01 INFO - mozversion == 1.4 11:27:01 INFO - psutil == 3.1.1 11:27:01 INFO - requests == 1.2.3 11:27:01 INFO - wsgiref == 0.1.2 11:27:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:01 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 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:27:01 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 11:27:01 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 11:27:01 INFO - Using env: {'DISPLAY': ':0', 11:27:01 INFO - 'HOME': '/home/cltbld', 11:27:01 INFO - 'LANG': 'en_US.UTF-8', 11:27:01 INFO - 'LANGUAGE': 'en_US:en', 11:27:01 INFO - 'LOGNAME': 'cltbld', 11:27:01 INFO - 'MAIL': '/var/mail/cltbld', 11:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:01 INFO - 'MOZ_NO_REMOTE': '1', 11:27:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:01 INFO - 'NO_EM_RESTART': '1', 11:27:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:01 INFO - 'PWD': '/builds/slave/test', 11:27:01 INFO - 'SHELL': '/bin/bash', 11:27:01 INFO - 'SHLVL': '1', 11:27:01 INFO - 'TERM': 'linux', 11:27:01 INFO - 'TMOUT': '86400', 11:27:01 INFO - 'USER': 'cltbld', 11:27:01 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:01 INFO - '_': '/tools/buildbot/bin/python'} 11:27:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:01 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)) 11:27:01 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)) 11:27:01 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)) 11:27:01 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)) 11:27:01 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)) 11:27:01 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)) 11:27:01 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)) 11:27:01 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)) 11:27:01 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)) 11:27:01 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:27:01 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 11:27:01 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 11:27:01 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 11:27:01 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 11:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:27:03 INFO - Installing collected packages: simplejson 11:27:03 INFO - Running setup.py install for simplejson 11:27:03 INFO - building 'simplejson._speedups' extension 11:27:03 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 11:27:03 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 11:27:04 INFO - Successfully installed simplejson 11:27:04 INFO - Cleaning up... 11:27:04 INFO - Return code: 0 11:27:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:27: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')]} 11:27:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:27: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')]} 11:27:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:27:04 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 0xba71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6fb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf149c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, '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': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', '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 11:27:04 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 11:27:04 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 11:27:04 INFO - Using env: {'DISPLAY': ':0', 11:27:04 INFO - 'HOME': '/home/cltbld', 11:27:04 INFO - 'LANG': 'en_US.UTF-8', 11:27:04 INFO - 'LANGUAGE': 'en_US:en', 11:27:04 INFO - 'LOGNAME': 'cltbld', 11:27:04 INFO - 'MAIL': '/var/mail/cltbld', 11:27:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:04 INFO - 'MOZ_NO_REMOTE': '1', 11:27:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:04 INFO - 'NO_EM_RESTART': '1', 11:27:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:04 INFO - 'PWD': '/builds/slave/test', 11:27:04 INFO - 'SHELL': '/bin/bash', 11:27:04 INFO - 'SHLVL': '1', 11:27:04 INFO - 'TERM': 'linux', 11:27:04 INFO - 'TMOUT': '86400', 11:27:04 INFO - 'USER': 'cltbld', 11:27:04 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:27:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:04 INFO - '_': '/tools/buildbot/bin/python'} 11:27:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:27:04 INFO - Cleaning up... 11:27:04 INFO - Return code: 0 11:27:04 INFO - Running post-action listener: _resource_record_post_action 11:27:04 INFO - Running post-action listener: _start_resource_monitoring 11:27:04 INFO - Starting resource monitoring. 11:27:04 INFO - [mozharness: 2017-09-23 18:27:04.345185Z] Finished create-virtualenv step (success) 11:27:04 INFO - [mozharness: 2017-09-23 18:27:04.346289Z] Running install step. 11:27:04 INFO - Running pre-action listener: _resource_record_pre_action 11:27:04 INFO - Running main action method: install 11:27:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:27:04 INFO - Reading from file tmpfile_stdout 11:27:04 INFO - Detecting whether we're running mozinstall >=1.0... 11:27:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:27:04 INFO - Reading from file tmpfile_stdout 11:27:04 INFO - Output received: 11:27:04 INFO - Usage: mozinstall [options] installer 11:27:04 INFO - Options: 11:27:04 INFO - -h, --help show this help message and exit 11:27:04 INFO - -d DEST, --destination=DEST 11:27:04 INFO - Directory to install application into. [default: 11:27:04 INFO - "/builds/slave/test"] 11:27:04 INFO - --app=APP Application being installed. [default: firefox] 11:27:04 INFO - mkdir: /builds/slave/test/build/application 11:27:04 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'] 11:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:27:22 INFO - Reading from file tmpfile_stdout 11:27:22 INFO - Output received: 11:27:22 INFO - /builds/slave/test/build/application/firefox/firefox 11:27:22 INFO - Running post-action listener: _resource_record_post_action 11:27:22 INFO - [mozharness: 2017-09-23 18:27:22.573968Z] Finished install step (success) 11:27:22 INFO - [mozharness: 2017-09-23 18:27:22.574222Z] Running setup-mitmproxy step. 11:27:22 INFO - Running pre-action listener: _resource_record_pre_action 11:27:22 INFO - Running main action method: setup_mitmproxy 11:27:22 INFO - Skipping: mitmproxy is not required 11:27:22 INFO - Running post-action listener: _resource_record_post_action 11:27:22 INFO - [mozharness: 2017-09-23 18:27:22.575357Z] Finished setup-mitmproxy step (success) 11:27:22 INFO - [mozharness: 2017-09-23 18:27:22.575578Z] Running run-tests step. 11:27:22 INFO - Running pre-action listener: _resource_record_pre_action 11:27:22 INFO - Running pre-action listener: _set_gcov_prefix 11:27:22 INFO - Running main action method: run_tests 11:27:22 WARNING - Try message not found. 11:27:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:27:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:27:22 INFO - Python 2.7.3 11:27:22 INFO - Return code: 0 11:27:22 INFO - grabbing minidump binary from tooltool 11:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:27:22 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 0xf11d60>, '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 0xf12a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf12ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:27:22 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 11:27:22 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 11:27:22 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 11:27:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:27:23 INFO - Return code: 0 11:27:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:27:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:27:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:27:23 INFO - ENV: RUST_BACKTRACE is now full 11:27:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:27:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:27:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 11:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 11:27:23 INFO - Using env: {'DISPLAY': ':0', 11:27:23 INFO - 'HOME': '/home/cltbld', 11:27:23 INFO - 'LANG': 'en_US.UTF-8', 11:27:23 INFO - 'LANGUAGE': 'en_US:en', 11:27:23 INFO - 'LOGNAME': 'cltbld', 11:27:23 INFO - 'MAIL': '/var/mail/cltbld', 11:27:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:23 INFO - 'MOZ_ACCELERATED': '1', 11:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:23 INFO - 'MOZ_NO_REMOTE': '1', 11:27:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:23 INFO - 'MOZ_WEBRENDER': '1', 11:27:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:23 INFO - 'NO_EM_RESTART': '1', 11:27:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:23 INFO - 'PWD': '/builds/slave/test', 11:27:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:23 INFO - 'RUST_BACKTRACE': 'full', 11:27:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:27:23 INFO - 'SHELL': '/bin/bash', 11:27:23 INFO - 'SHLVL': '1', 11:27:23 INFO - 'TERM': 'linux', 11:27:23 INFO - 'TMOUT': '86400', 11:27:23 INFO - 'USER': 'cltbld', 11:27:23 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:23 INFO - '_': '/tools/buildbot/bin/python'} 11:27:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 11:27:23 INFO - mozversion application_buildid: 20170923173344 11:27:23 INFO - mozversion application_changeset: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 11:27:23 INFO - mozversion application_display_name: Nightly 11:27:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:27:23 INFO - mozversion application_name: Firefox 11:27:23 INFO - mozversion application_remotingname: firefox 11:27:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:27:23 INFO - mozversion application_vendor: Mozilla 11:27:23 INFO - mozversion application_version: 58.0a1 11:27:23 INFO - mozversion platform_buildid: 20170923173344 11:27:23 INFO - mozversion platform_changeset: 2d9d0bebb5c66ba3dfdffd713a888a8c49730631 11:27:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:27:23 INFO - mozversion platform_version: 58.0a1 11:27:23 INFO - using testdate: 1506191243 11:27:23 INFO - actual date: 1506191243 11:27:23 INFO - starting webserver on 'localhost:36100' 11:27:23 INFO - SUITE-START | Running 2 tests 11:27:23 INFO - TEST-START | dromaeo_css 11:27:23 INFO - Initialising browser for dromaeo_css test... 11:27:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36100/getInfo.html -profile /tmp/tmpD1b0xL/profile 11:27:23 INFO - TEST-INFO | started process 3576 (/builds/slave/test/build/application/firefox/firefox http://localhost:36100/getInfo.html) 11:27:35 INFO - TEST-INFO | 3576: exit 0 11:27:35 INFO - Browser initialized. 11:27:35 INFO - Running cycle 1/1 for dromaeo_css test... 11:27:35 INFO - Using env: {'DISPLAY': ':0', 11:27:35 INFO - 'HOME': '/home/cltbld', 11:27:35 INFO - 'JSGC_DISABLE_POISONING': '1', 11:27:35 INFO - 'LANG': 'en_US.UTF-8', 11:27:35 INFO - 'LANGUAGE': 'en_US:en', 11:27:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:27:35 INFO - 'LOGNAME': 'cltbld', 11:27:35 INFO - 'MAIL': '/var/mail/cltbld', 11:27:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:27:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:27:35 INFO - 'MOZ_ACCELERATED': '1', 11:27:35 INFO - 'MOZ_CRASHREPORTER': '1', 11:27:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:27:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:27:35 INFO - 'MOZ_NO_REMOTE': '1', 11:27:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:27:35 INFO - 'MOZ_WEBRENDER': '1', 11:27:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:27:35 INFO - 'NO_EM_RESTART': '1', 11:27:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:27:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:27:35 INFO - 'PWD': '/builds/slave/test', 11:27:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:27:35 INFO - 'RUST_BACKTRACE': 'full', 11:27:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:27:35 INFO - 'SHELL': '/bin/bash', 11:27:35 INFO - 'SHLVL': '1', 11:27:35 INFO - 'TERM': 'linux', 11:27:35 INFO - 'TMOUT': '86400', 11:27:35 INFO - 'USER': 'cltbld', 11:27:35 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:27:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:27:35 INFO - '_': '/tools/buildbot/bin/python'} 11:27:35 INFO - TEST-INFO | started process 3823 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD1b0xL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:27:36 INFO - PID 3823 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:27:36 INFO - PID 3823 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:27:36 INFO - PID 3823 | 11:27:36 INFO - PID 3823 | (/builds/slave/test/build/application/firefox/firefox:3884): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:27:36 INFO - PID 3823 | 11:27:46 INFO - PID 3823 | RSS: Main: 190803968 11:27:46 INFO - PID 3823 | 11:30:44 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:36100/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36100/tests/dromaeo/cssquery-ext.html) 11:30:46 INFO - PID 3823 | RSS: Main: 191750144 11:30:46 INFO - PID 3823 | 11:33:48 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:36100/tests/dromaeo/cssquery-ext.html (next: http://localhost:36100/tests/dromaeo/cssquery-jquery.html) 11:33:48 INFO - PID 3823 | RSS: Main: 193359872 11:33:48 INFO - PID 3823 | 11:36:50 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:36100/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36100/tests/dromaeo/cssquery-mootools.html) 11:36:51 INFO - PID 3823 | RSS: Main: 195477504 11:36:51 INFO - PID 3823 | 11:39:53 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:36100/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36100/tests/dromaeo/cssquery-prototype.html) 11:39:54 INFO - PID 3823 | RSS: Main: 190439424 11:39:54 INFO - PID 3823 | 11:42:57 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:36100/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36100/tests/dromaeo/cssquery-yui.html) 11:42:58 INFO - PID 3823 | RSS: Main: 194609152 11:42:58 INFO - PID 3823 | 11:46:00 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:36100/tests/dromaeo/cssquery-yui.html (next: http://localhost:36100/tests/dromaeo/cssquery-dojo.html) 11:46:00 INFO - PID 3823 | RSS: Main: 197611520 11:46:00 INFO - PID 3823 | 11:46:00 INFO - PID 3823 | __start_tp_report 11:46:00 INFO - PID 3823 | _x_x_mozilla_page_load 11:46:00 INFO - PID 3823 | _x_x_mozilla_page_load_details 11:46:00 INFO - PID 3823 | |i|pagename|runs| 11:46:00 INFO - PID 3823 | |0;dojo.html;3082.7071428214317;3102.4880894652397;3120.687931206879;3120.8751649934;3129.31155145868;6199.225096862892;6307.117003619491;6322.589031712935;6366.108744775729;6415.71129299182;6209.565330426872;6284.622922624634;6299.118123462719;6302.653354065525;6313.936860631392;5758.769649214037;5782.392848750884;5844.736986835582;5846.0354041583305;5846.356900740915;5855.824325270249;5916;5916.556258280624;5934.465898069173;5956.315023772257;5748.65508069513;5830.542364408898;5840;5884.146798714184;5942.108683697436;5463.289772329612;5609.130584759328;5722.628029178131;5821.592488525805;5865.178874957509;5747.942520574783;5754.827355179351;5828.825135245949;5887.116932460122;5896.734646940855;6141.477974372184;6235.719392627298;6374.458171055466;6393.584417012879;6404.615723056585;6757.290484499106;6845.178578570553;6871.622060786654;6883.965580172117;6886.139232595926;6804.353586409283;6809.421199198074;6837.111175547197;6885.380315771605;6925.995730619032;6131.63210207389;6257.8748425031245;6312.116303717483;6376.776812811576;6435.613863168225;6952.130983627047;7013.544119632207;7079.433645308363;7094.574325540484;7096.112985876765;6451.483881289486;6549.083128362033;6576;6579.2104947406615;6613.537052406382;6666.600003999775;6671.86656266872;6693.397594216543;6712.026756120335;6717.193936727624;6605.438537724347;6645.66771661415;6654.434373078246;6656;6661.4004739573675;6721.059051732761;6732.124823772878;6841.179058512911;6844.828879277959;6854.862902741917;5297.735113244323;5313.362396512443;5315.255864178941;5398.163284690879;5413.566914646819;4592.770361481913;4620.306953956933;4664.836730714409;4672.415948006499;4707.7881495332795;5662.546996240373;5741.5119714824705;5784.797532086441;5798.710064496759;5828.766849326064;6685.264620891698;6711.932880671228;6743.865122697617;6748;6759.155105611799;5755.194272801811;5772.942270577324;5833.82498525045;5875.030619947661;5877.206577112121;5855.824325270163;5917.260342457193;5928.940710592925;5931.199288096138;5937.198478205473;6533.444657204096;6555.789432075292;6634.03806448072;6657.966710166515;6672.432843208382;6652.634105124264;6762.053312536151;6820.590764554143;6918.792436226921;6926.896096558455;6431.903521447276;6541;6604.867902642017;6622.337766223339;6639.767608133692;6753.1220941277315;6758;6785.660716964231;6788;6818.249992500721;6756.290589488033;6850.486213533905;6922.169339679271;6933.479989000744;6940.201876784033;6468.773592924225;6582.76960306387;6583.63789991555;6606.6366349849295;6697.765578204934;6525.8694826104165;6529.085927970183;6533.934660653333;6546.607203567801;6550.574212676161;6406.13517175194;6478.2550006749825;6480.027995800479;6513.837154071186;6524.6411447368955;6582.17722784652;6612.206535215805;6635.502337324817;6686.933130668631;6746.291639377991;6256.718447669775;6367.9044814328745;6382.840428989127;6485.156929599122;6514.055461958084 11:46:00 INFO - PID 3823 | |1;ext.html;868.9131086891311;875.7504111328273;885.07067579042;910.508325504228;911.2436677557628;40641.79679101608;41019.79490102549;41152.38271425931;41465.17069658605;41493.17013659725;37472;40994;40996.18007639846;41153;41212;7794.688212471494;7986.440949133563;7996.400269979746;8018.238267364591;8022.277994980451;7876.448648594601;7913.604319784016;7993.640286187136;8026.398020148483;8036.799080023017;7987.400944929124;7994.440389172761;8015.158408367125;8044.758657240262;8063.435559510836;7965.840683186362;7971.800704982364;7978.521288722666;7983.3214176795045;8036.035675718893;7929.762107136795;8004.63979120941;8005.0794158671915;8009.279164875189;8013.358931285541;37718.811405943066;38082.42876356857;38205.42691859624;38207;38254.80872595647;3434.3131373725105;3468.6357932416986;3473.8263086845554;3480.443129099357;3483.494893240466;30857;30997;31041.37917241665;31046.84476577625;31226.843865780527;4997.150484417622;5021;5023;5096.541311281965;5165.302684137631;184682;184704.0764796181;186269.99999999863;186529;186621;46536.5346346539;46728.766356168;47098.76450617793;47264.7636761814;47287.29069063967;49529;49746.25380619294;49849;49881.25178122331;52997.47002530003;14092.929535352258;14366.425342986164;14422.711545769025;14454.855451445352;14499.637509062359;11465.254758440675;11577;11600.593979210857;11619.128565357632;11649.767004660029;14132.929335353258;14291.785623215661;14349.78026867728;14419.639509012359;14479.927600362142;5508.807191748269;5615.2700148981185;5646.20953066571;5771.365149833515;5776.595638305278;957.7174733453647;967.6419724701906;998.8851282102506;1004.7689031522791;1016.1159790981745;22113;22532.436689082904;22581.322560323217;22614.208502702328;22762.886185568965;1088.2436706488913;1093.578972095749;1093.912487001054;1094.1247002398209;1097.720081379243;11920.225185363097;11964.162508624313;12002.279863208236;12011.459484323226;12145.939270303592;230818;231647;232307;232387;233438;43575.34636980448;43866.56133438625;49779.50220497821;49782.75108624506;50150.247746283836;1385.9653508662163;1390.4994202087444;1395.797609346639;1402.4320150339129;1414.5332040426636;26212.606810898233;26833.195004149908;26982.247343072522;27049.59425608657;27221;37277.6272237285;37492.06269843276;37495.43756843595;37540;37571.81214093912;197750;198033;198502;200354;200460;34956.30087398187;35023.124421888635;35073;35100.297994039465;35666.64333356633;41353;43007.78496107499;43037.139257215305;43244.56755432531;43767.56232437635;30822.69177308288;31102.844485777427;31139.532907006866;31163.688363116078;31315.37369252648;30648.846755766077;30730;30851.537226941164;31335.21661958378;31406.842965785938;35341.469877951335;35352;35738;35958.64041359553;36115.81942090273;30275.545866812456;30721.231969200948;30783.846080769454;30946.845265774427;31218.531722024643;19737;19998.800011999694;20072.69890951666;20338.186472540932;20476.180952761722 11:46:00 INFO - PID 3823 | |2;jquery.html;596.7536600891149;601.3835818286256;605.4460168945413;610.4749915073039;618.5793660310989;21337.25319613814;21983.340499785012;22121.557568848613;22129.33611991639;22132.8893355533;21592.56814863702;23714.17000404986;24189;24457.75542244577;24532.264032079023;6527.1188389567515;6561.901571476432;6692.464602831762;6692.9330706693045;6693.129893113888;6586.012098185262;6621.503387245952;6665.100211471462;6668.799936001928;6670.966645166792;6296.590721603081;6515;6581.506387020969;6588.143541339619;6652.467802575807;6461.80614581561;6500.642464664442;6556.803295901131;6575.243846957614;6585.209774827052;6032.366601506867;6073.817785466443;6088.726007329681;6099.847503812396;6171.166892469504;26994.865025674942;27605.171844844688;27744.861275693493;27767.722322776717;27863.885444582193;1954.4332143678316;1961.3723608445302;1965.5675751334684;1974.6544354738007;1976.0613708488497;18209.817901820945;18858.811411885843;19403.126859291224;19436.902815485973;19472;2408.8193385496156;2437.549053425122;2443.584590619599;2456.4841383309526;2465.149523414419;123809.38095309556;129931;130327;134078;139423;14353.823908710807;14663.353590994871;14688.50295449776;14762.778558321634;14834.332455039548;22754.886225568767;22920.08319667228;22980.425489362566;23033.07867685274;23145.768542314698;9151.77120571991;9207.723768286962;9249.768755781024;9290.767730806649;9485.146336829719;8356.874646880302;8367.079621241659;8399.664013439395;8433.409661323649;8555.743327700178;9128.361014728906;9213.953930230306;9223.631054757869;9238.722838314861;9420.434773913586;2811.536096544088;2842.5167721486982;2874.094660182036;2882.3514709190263;2901.3762041161253;940.9294302927308;963.1283688261994;977.6627063663025;978.9706308810747;980.887197972228;18100.36648717315;18507.074646267632;18551.90724046398;18567.25730970773;18746.250149993848;1003.4882210072915;1004.542932965499;1010.4543546484963;1021.070825548747;1021.1218352217087;10278.691639250834;10321.74195645115;10368;10512.632057877938;10644.09525190352;437150;451353;451685;455136;458687;102280;105809;105963;107159.46420268004;107533.4623326894;1037.8806437259811;1112.3103675720943;1143.0398465289197;1174.2308787744041;1181.6986668399504;19385.806141938963;19608.60782784308;19897;20045.398638040882;20062.79937200609;54000;54623.726881365335;54832.451675482735;55155.44844551664;55338;63770.68114659397;65837.34162658312;66233.33766662271;66772.3322766766;67151;13937;14140.222287774272;14168.716625667636;14211.076280041763;14287.142771433748;22120.557588848456;22171.334859954226;22291.554168916857;22323.44191395228;22415.551688966454;17304.307827686753;17724.46826595204;17776.644467110327;17808.3767068152;18044.90977545104;15669;15749;15944.760828587348;16133.193340333171;16639.833601663828;16941.32234710598;17971.910140449214;18103.72844407361;18207.45377638673;18267.90866045661;13912;13942.442302307794;13968.161910285009;13978.720425591227;13984.160950343012;17223.311067557657;17516.299348026445;17574.38489652907;17643.382481613597;17807.59597454379 11:46:00 INFO - PID 3823 | |3;mootools.html;1345.8250427444432;1391.0262816028783;1401.1242973141793;1412.773956167012;1425.0309789343248;15147.621309467255;15736.921315393436;15809.288582013809;15897.523074307775;15901.68196636068;15394.076355418683;15422.305996230169;15528.301226444808;15544.68910621787;15566.45517406891;2806.144126041567;2815.338395477061;2836.375997280595;2871.798974071816;2872.454233695591;2747.3955729739423;2802.6636803583597;2815.4650616382824;2819.3233623930314;2822.0969289827162;2733.8381187995124;2802.1033269353816;2836.6170566973506;2838.3046153692376;2850.087971849009;2818.985905070482;2840.4603125406106;2858.19970408286;2870.5981162637145;2875.7555607773365;2688.5832695932163;2713.498002869456;2724.3189202699327;2727.904523341693;2741.040635777482;15128.848711512854;15212.923935380251;15406.152661603608;15463.536093917088;15493.535193944199;7530.171681115073;7837.6864925402915;7914.5251284922515;7925.405594580429;7928.7621371358955;5521;5622.606417550773;5626.606137570372;5689.687067211302;5733.569982251306;1969.33042765459;1996.46095554201;2016.2842191022123;2037.663785475395;2039.5207126325301;27081.32296692579;27130.5930411044;27260.31849203766;27299;27310.86344568304;6586.473082153416;6782.287859774751;6803.387695107464;7030.402415794612;7074.186468556079;17603;17605.295788168587;17630.559236019202;17657.911710441367;17681.38115165963;8056.476329038594;8182.7954301142945;8221.424500284922;8244.093149753642;8271.090180080308;7170.103737032871;7282.725523033554;7300.758870991944;7384.704611815575;7572.621368931642;5298.682079075268;5425.538829199562;5426.62013659048;5499.752511137009;5540.252065971123;1430.6924011337612;1574.126359870283;1581.549258461333;1596.0503500417233;1596.9201539922958;689.4932224814758;872.7556284240422;882.911708829112;895.7581453007651;896.8834051573253;8402.907891778534;8441.409101362968;8528.914710852936;8571.571421428904;8580.2706769924;967.6758285974137;1000.5097502223858;1000.7698229407277;1007.6473234367912;1017.9287449878585;5123.308353372322;5138.434772175057;5156.8968620627375;5210.270562121306;5331.040412725747;21389.46526336854;21696.24063157782;22337.664935025987;22493.55012899733;22538.098476061103;21635;21777.128914843546;22162;22235.221767238392;22325.776742232694;11306.378149201708;11712.648620541397;11760.529578816922;11772.70568235818;11806.46870890812;7962.124166341813;8091.83816323682;8121.106678265504;8137.471064380797;8188.83622327562;18334.358297459527;18754;18793.436196914114;18916.621667566847;19032.33386831454;19552.706709399656;19733.112009959874;19913.900430497753;20030.39908802738;20283.188672453525;9870.901290986998;11061.889381106086;11073.94463027712;11075.224734268524;11089.778204435705;14232.430702771773;14286.785698214742;14455;14457.132572045712;14461.638459038608;9729.756756081155;9742.853857192005;10630;11202.66392008241;11332.320060796379;11067.114630829676;11219.60731374398;11251.7749645005;11260.493277802683;11433.371164586028;9879.160271377013;9942.801143977225;9977.987531259041;9999.35004224723;10035.197184225099;6836.692348844214;6898.241193468814;6916.377526022682;6926.619035952869;6955.8956615649795;6870.3473170048765;6963.61700106499;6982.546134501445;6993.090898183407;7054 11:46:00 INFO - PID 3823 | |4;prototype.html;509.2183498330064;542.574079347712;544.2326319888957;548.9011977843986;551.4292707048205;6289.5597308188335;6308.4322410983;6330.018847078704;6357.61854288743;6390.105385246064;6225.844353891143;6252.718627661755;6269.435750782429;6274.4039316265;6327.5570710050315;5212.739363031852;5223.373195216579;5245.452591485521;5275.261463395128;5308.893822123555;5225.320708307933;5246.822568634012;5264;5281.70950597717;5303.390110137326;4821.421429428474;5001;5011.6695736482525;5052.797888084472;5053.444121146671;4975.203967365241;5015.523525265094;5054.823081192178;5056.620753443488;5057.873553161164;4430.379746835446;4567.474740404829;4594.448666160049;4598.379218805485;4601.953980460219;6082.330943596201;6140.263168419773;6168.920438923171;6182.289036760785;6216.937830621681;5772.855678608026;5773.653580785166;5793.246877905845;5833.037548804442;5836.824895253107;6397.880370935114;6575;6637.668116594151;6649.437382215224;6668.166479190101;4911.4106307243;4939.876503087416;4969.726665033421;4977.527134922213;4990.30135780991;6572.619749852537;6730.596164230162;6742.662866856637;6768.796936091925;6789.490788190906;5065.417476990119;5079.755459912345;5081.441041485434;5083.38999320084;5107.7446127693465;5122.129238029544;5142.2029585413575;5183.948160518422;5189.273501709764;5191.299174611379;5759.625624334405;5765.164051212634;5776.451237132465;5785.553611597101;5809.3609702932645;6630.115899583697;6643.2028156620545;6651.501137414713;6660;6670.299618540081;1768.9563157736998;1773.796013458469;1779.510634575502;1783.3758184635533;1794.0312231395162;1172.3786393211612;1235.7281398092405;1250.6310638361706;1253.2417887178126;1263.4819723913151;5509.586780991442;5668.744906479218;5679.5172410345585;5704.172894930211;5731.484166425042;6565.770198043046;6626.801195964129;6655.833604159935;6713.26154123046;6717.6977036033495;5621.353544342372;5663.801766938137;5700.118960742945;5745.540356771448;5749.942500574941;3469.8959031229106;3500.64993500653;3509.2104276537575;3541.7166626669805;3547.52108465359;6237.6277219011745;6316.30520642729;6368.617882927039;6368.968155159195;6390.9360906391275;6276.717547710364;6880.759173428906;6891.416463438984;6900.482463815234;6924.965375173092;6257.812265632129;6401.167848179707;6552.951527755632;6555.901661475082;6573.638449885302;6494.74021039172;6591.725854580162;6601.3728695773825;6652.866942661023;6687.786694382702;6614.272430032596;6619.371159739817;6656.7670131545165;6680.665966701743;6732.461403087644;6398.936010639834;6524.934750652433;6538.574992625464;6539.967300163468;6552.3447655234095;5082.898342033212;5083.694978301166;5108.821191258288;5118.283440318284;5118.820841270538;6356.2054743157105;6568.113304703804;6611.537192396486;6633.270340262664;6655;6355.491560675043;6479.611223326616;6554.934450655433;6628.867422651617;6631.63526006055;6430.582012169194;6469.385408386196;6489.675516224075;6507.316731743288;6590.505712071518;6418.390252925965;6424.582402143846;6460.45086167681;6471.870562588816;6581.3418658135715;6531.673416329259;6594.769183078761;6616;6619.054520738226;6619.238787539494;6277.937220627735;6349.904751428825;6358.745650173942;6385;6420.582662126947 11:46:00 INFO - PID 3823 | |5;yui.html;879.156010230179;901.9368644194909;912.4570880326207;920.2499962530537;937.9249660027199;19149.234030638774;19944.900275498603;19964.60070798585;20051.799482005175;20149.798502014975;17327.133643317848;17455.82544174558;17478.825211747877;17480.125993700294;17480.912595437036;948.7248697877607;949.1268033409245;951.7144856543044;954.2938225712957;957.1433566957569;976.2726768557435;979.9118079372855;980.9901900981006;981.087588542655;981.4258658684678;998.3161534348994;1023.6775415744054;1025.2003437318865;1028.7633844215798;1032.0659802878406;1009.6466236817055;1011.5801942193981;1011.6610935336671;1012.4330374989993;1018.9745256368614;734.100726609904;746.902902622661;747.4879707400448;747.6075060593178;749.9962500187519;19357.806421935882;20292.898535507375;20437.795622043737;20486.078126484343;20530.589388212302;21160.259390921245;21315.573688526143;21706.34880953574;21717.2398966037;21727;16841.494755157244;17128.57178570546;17178.828211717973;17234.224459899207;17343.21955512005;3725.3853114236117;3739.7943113128767;3740.812959352049;3764.171882185915;3769.4157405602177;124510;134190;136099.31950340184;136808;137946;17482.213300401512;17748.55628609269;17892.10539473021;17911.014894180684;17933.282668693366;31207.375852482823;31229.21926951799;31268;31400.842995784875;31449.842750786098;9124.45253284805;9240.122188392092;9269.351145419756;9360.953195234117;9487.66793162236;4393.1872603568445;4481.305397663367;4495.730256184639;4509.864704058883;4511.097780443924;5554.805581804618;5572.331320241516;5674.205611214434;5696.202531645573;5703.857403564861;1077.7413420778958;1113.4432783608195;1122.5846436818267;1128.9830652540218;1130.3895896215952;685.7736946807541;699.8600279944031;700.1877821726772;708.890122031086;710.1159056974101;11383.601573944872;11790.646280611596;11943.641690749291;11978.700532486757;11997.160198786001;664.9335066493312;675.5777638975641;678.6708446403491;691.5781373362245;699.923008469068;5184.377874655065;5191.896162076738;5201.53186213235;5389.353277606712;5437.34751829783;114696;115818;120914.39542802406;121670;124385;77957.57458253174;102169.48915255377;106599.46700266605;111419;112172;93266.53366733258;94786;95062.52468737612;97755;98198.50900745594;12262.4481898313;12823.93588032054;12831.743365132459;12878.034147438791;12922.901553368069;42793.78603106965;42817.35773963455;44205;44583.55416445924;44766.55233447624;58154;58251;59322.40677593169;59431.70284148725;59795;17453.38913138034;17711;17932.551686207426;18027.819721802614;18029.909850450666;20005.59988800245;20033.899830500755;20150.59698806045;20484.590308194052;20558.691619626017;15931.840681593036;16094.356225751308;16118.274677639769;16198.919005404898;16441.83558164403;18855.528611784815;18934;19090.23639054422;19130;19343.032848357245;15064.472743453927;15066.171360575274;15100.697986040437;15148.697026059637;15840.683186336439;11937.940310298392;12111.636650900135;12123.454444550192;12350.320732359447;12508.812367814307;12217.69455763613;12324.876751232372;12577.87422125767;12606.936965315115;12711.80932286035 11:46:00 INFO - PID 3823 | __end_tp_report 11:46:00 INFO - PID 3823 | __start_cc_report 11:46:00 INFO - PID 3823 | _x_x_mozilla_cycle_collect,276 11:46:00 INFO - PID 3823 | __end_cc_report 11:46:00 INFO - PID 3823 | __startTimestamp1506192360614__endTimestamp 11:46:00 INFO - PID 3823 | 11:46:00 INFO - PID 3823 | ------- Summary: start ------- 11:46:00 INFO - PID 3823 | Number of tests: 6 11:46:00 INFO - PID 3823 | 11:46:00 INFO - PID 3823 | [#0] dojo.html Cycles:165 Average:6224.57 Median:6478.26 stddev:757.54 (11.7%) stddev-sans-first:718.65 11:46:00 INFO - PID 3823 | Values: 3082.7 3102.5 3120.7 3120.9 3129.3 6199.2 6307.1 6322.6 6366.1 6415.7 6209.6 6284.6 6299.1 6302.7 6313.9 5758.8 5782.4 5844.7 5846.0 5846.4 5855.8 5916.0 5916.6 5934.5 5956.3 5748.7 5830.5 5840.0 5884.1 5942.1 5463.3 5609.1 5722.6 5821.6 5865.2 5747.9 5754.8 5828.8 5887.1 5896.7 6141.5 6235.7 6374.5 6393.6 6404.6 6757.3 6845.2 6871.6 6884.0 6886.1 6804.4 6809.4 6837.1 6885.4 6926.0 6131.6 6257.9 6312.1 6376.8 6435.6 6952.1 7013.5 7079.4 7094.6 7096.1 6451.5 6549.1 6576.0 6579.2 6613.5 6666.6 6671.9 6693.4 6712.0 6717.2 6605.4 6645.7 6654.4 6656.0 6661.4 6721.1 6732.1 6841.2 6844.8 6854.9 5297.7 5313.4 5315.3 5398.2 5413.6 4592.8 4620.3 4664.8 4672.4 4707.8 5662.5 5741.5 5784.8 5798.7 5828.8 6685.3 6711.9 6743.9 6748.0 6759.2 5755.2 5772.9 5833.8 5875.0 5877.2 5855.8 5917.3 5928.9 5931.2 5937.2 6533.4 6555.8 6634.0 6658.0 6672.4 6652.6 6762.1 6820.6 6918.8 6926.9 6431.9 6541.0 6604.9 6622.3 6639.8 6753.1 6758.0 6785.7 6788.0 6818.2 6756.3 6850.5 6922.2 6933.5 6940.2 6468.8 6582.8 6583.6 6606.6 6697.8 6525.9 6529.1 6533.9 6546.6 6550.6 6406.1 6478.3 6480.0 6513.8 6524.6 6582.2 6612.2 6635.5 6686.9 6746.3 6256.7 6367.9 6382.8 6485.2 6514.1 11:46:00 INFO - PID 3823 | 11:46:00 INFO - PID 3823 | [#1] ext.html Cycles:180 Average:37132.57 Median:35220.88 stddev:53497.25 (151.9%) stddev-sans-first:53578.03 11:46:00 INFO - PID 3823 | Values: 868.9 875.8 885.1 910.5 911.2 40641.8 41019.8 41152.4 41465.2 41493.2 37472.0 40994.0 40996.2 41153.0 41212.0 7794.7 7986.4 7996.4 8018.2 8022.3 7876.4 7913.6 7993.6 8026.4 8036.8 7987.4 7994.4 8015.2 8044.8 8063.4 7965.8 7971.8 7978.5 7983.3 8036.0 7929.8 8004.6 8005.1 8009.3 8013.4 37718.8 38082.4 38205.4 38207.0 38254.8 3434.3 3468.6 3473.8 3480.4 3483.5 30857.0 30997.0 31041.4 31046.8 31226.8 4997.2 5021.0 5023.0 5096.5 5165.3 184682.0 184704.1 186270.0 186529.0 186621.0 46536.5 46728.8 47098.8 47264.8 47287.3 49529.0 49746.3 49849.0 49881.3 52997.5 14092.9 14366.4 14422.7 14454.9 14499.6 11465.3 11577.0 11600.6 11619.1 11649.8 14132.9 14291.8 14349.8 14419.6 14479.9 5508.8 5615.3 5646.2 5771.4 5776.6 957.7 967.6 998.9 1004.8 1016.1 22113.0 22532.4 22581.3 22614.2 22762.9 1088.2 1093.6 1093.9 1094.1 1097.7 11920.2 11964.2 12002.3 12011.5 12145.9 230818.0 231647.0 232307.0 232387.0 233438.0 43575.3 43866.6 49779.5 49782.8 50150.2 1386.0 1390.5 1395.8 1402.4 1414.5 26212.6 26833.2 26982.2 27049.6 27221.0 37277.6 37492.1 37495.4 37540.0 37571.8 197750.0 198033.0 198502.0 200354.0 200460.0 34956.3 35023.1 35073.0 35100.3 35666.6 41353.0 43007.8 43037.1 43244.6 43767.6 30822.7 31102.8 31139.5 31163.7 31315.4 30648.8 30730.0 30851.5 31335.2 31406.8 35341.5 35352.0 35738.0 35958.6 36115.8 30275.5 30721.2 30783.8 30946.8 31218.5 19737.0 19998.8 20072.7 20338.2 20476.2 11:46:00 INFO - PID 3823 | 11:46:00 INFO - PID 3823 | [#2] jquery.html Cycles:180 Average:32705.81 Median:22585.22 stddev:76031.29 (336.6%) stddev-sans-first:76206.36 11:46:00 INFO - PID 3823 | Values: 596.8 601.4 605.4 610.5 618.6 21337.3 21983.3 22121.6 22129.3 22132.9 21592.6 23714.2 24189.0 24457.8 24532.3 6527.1 6561.9 6692.5 6692.9 6693.1 6586.0 6621.5 6665.1 6668.8 6671.0 6296.6 6515.0 6581.5 6588.1 6652.5 6461.8 6500.6 6556.8 6575.2 6585.2 6032.4 6073.8 6088.7 6099.8 6171.2 26994.9 27605.2 27744.9 27767.7 27863.9 1954.4 1961.4 1965.6 1974.7 1976.1 18209.8 18858.8 19403.1 19436.9 19472.0 2408.8 2437.5 2443.6 2456.5 2465.1 123809.4 129931.0 130327.0 134078.0 139423.0 14353.8 14663.4 14688.5 14762.8 14834.3 22754.9 22920.1 22980.4 23033.1 23145.8 9151.8 9207.7 9249.8 9290.8 9485.1 8356.9 8367.1 8399.7 8433.4 8555.7 9128.4 9214.0 9223.6 9238.7 9420.4 2811.5 2842.5 2874.1 2882.4 2901.4 940.9 963.1 977.7 979.0 980.9 18100.4 18507.1 18551.9 18567.3 18746.3 1003.5 1004.5 1010.5 1021.1 1021.1 10278.7 10321.7 10368.0 10512.6 10644.1 437150.0 451353.0 451685.0 455136.0 458687.0 102280.0 105809.0 105963.0 107159.5 107533.5 1037.9 1112.3 1143.0 1174.2 1181.7 19385.8 19608.6 19897.0 20045.4 20062.8 54000.0 54623.7 54832.5 55155.4 55338.0 63770.7 65837.3 66233.3 66772.3 67151.0 13937.0 14140.2 14168.7 14211.1 14287.1 22120.6 22171.3 22291.6 22323.4 22415.6 17304.3 17724.5 17776.6 17808.4 18044.9 15669.0 15749.0 15944.8 16133.2 16639.8 16941.3 17971.9 18103.7 18207.5 18267.9 13912.0 13942.4 13968.2 13978.7 13984.2 17223.3 17516.3 17574.4 17643.4 17807.6 11:46:00 INFO - PID 3823 | 11:46:00 INFO - PID 3823 | [#3] mootools.html Cycles:180 Average:9461.23 Median:15022.35 stddev:6834.66 (45.5%) stddev-sans-first:6826.63 11:46:00 INFO - PID 3823 | Values: 1345.8 1391.0 1401.1 1412.8 1425.0 15147.6 15736.9 15809.3 15897.5 15901.7 15394.1 15422.3 15528.3 15544.7 15566.5 2806.1 2815.3 2836.4 2871.8 2872.5 2747.4 2802.7 2815.5 2819.3 2822.1 2733.8 2802.1 2836.6 2838.3 2850.1 2819.0 2840.5 2858.2 2870.6 2875.8 2688.6 2713.5 2724.3 2727.9 2741.0 15128.8 15212.9 15406.2 15463.5 15493.5 7530.2 7837.7 7914.5 7925.4 7928.8 5521.0 5622.6 5626.6 5689.7 5733.6 1969.3 1996.5 2016.3 2037.7 2039.5 27081.3 27130.6 27260.3 27299.0 27310.9 6586.5 6782.3 6803.4 7030.4 7074.2 17603.0 17605.3 17630.6 17657.9 17681.4 8056.5 8182.8 8221.4 8244.1 8271.1 7170.1 7282.7 7300.8 7384.7 7572.6 5298.7 5425.5 5426.6 5499.8 5540.3 1430.7 1574.1 1581.5 1596.1 1596.9 689.5 872.8 882.9 895.8 896.9 8402.9 8441.4 8528.9 8571.6 8580.3 967.7 1000.5 1000.8 1007.6 1017.9 5123.3 5138.4 5156.9 5210.3 5331.0 21389.5 21696.2 22337.7 22493.6 22538.1 21635.0 21777.1 22162.0 22235.2 22325.8 11306.4 11712.6 11760.5 11772.7 11806.5 7962.1 8091.8 8121.1 8137.5 8188.8 18334.4 18754.0 18793.4 18916.6 19032.3 19552.7 19733.1 19913.9 20030.4 20283.2 9870.9 11061.9 11073.9 11075.2 11089.8 14232.4 14286.8 14455.0 14457.1 14461.6 9729.8 9742.9 10630.0 11202.7 11332.3 11067.1 11219.6 11251.8 11260.5 11433.4 9879.2 9942.8 9978.0 9999.4 10035.2 6836.7 6898.2 6916.4 6926.6 6955.9 6870.3 6963.6 6982.5 6993.1 7054.0 11:46:00 INFO - PID 3823 | 11:46:00 INFO - PID 3823 | [#4] prototype.html Cycles:180 Average:5532.27 Median:6231.74 stddev:1524.03 (24.5%) stddev-sans-first:1480.94 11:46:00 INFO - PID 3823 | Values: 509.2 542.6 544.2 548.9 551.4 6289.6 6308.4 6330.0 6357.6 6390.1 6225.8 6252.7 6269.4 6274.4 6327.6 5212.7 5223.4 5245.5 5275.3 5308.9 5225.3 5246.8 5264.0 5281.7 5303.4 4821.4 5001.0 5011.7 5052.8 5053.4 4975.2 5015.5 5054.8 5056.6 5057.9 4430.4 4567.5 4594.4 4598.4 4602.0 6082.3 6140.3 6168.9 6182.3 6216.9 5772.9 5773.7 5793.2 5833.0 5836.8 6397.9 6575.0 6637.7 6649.4 6668.2 4911.4 4939.9 4969.7 4977.5 4990.3 6572.6 6730.6 6742.7 6768.8 6789.5 5065.4 5079.8 5081.4 5083.4 5107.7 5122.1 5142.2 5183.9 5189.3 5191.3 5759.6 5765.2 5776.5 5785.6 5809.4 6630.1 6643.2 6651.5 6660.0 6670.3 1769.0 1773.8 1779.5 1783.4 1794.0 1172.4 1235.7 1250.6 1253.2 1263.5 5509.6 5668.7 5679.5 5704.2 5731.5 6565.8 6626.8 6655.8 6713.3 6717.7 5621.4 5663.8 5700.1 5745.5 5749.9 3469.9 3500.6 3509.2 3541.7 3547.5 6237.6 6316.3 6368.6 6369.0 6390.9 6276.7 6880.8 6891.4 6900.5 6925.0 6257.8 6401.2 6553.0 6555.9 6573.6 6494.7 6591.7 6601.4 6652.9 6687.8 6614.3 6619.4 6656.8 6680.7 6732.5 6398.9 6524.9 6538.6 6540.0 6552.3 5082.9 5083.7 5108.8 5118.3 5118.8 6356.2 6568.1 6611.5 6633.3 6655.0 6355.5 6479.6 6554.9 6628.9 6631.6 6430.6 6469.4 6489.7 6507.3 6590.5 6418.4 6424.6 6460.5 6471.9 6581.3 6531.7 6594.8 6616.0 6619.1 6619.2 6277.9 6349.9 6358.7 6385.0 6420.6 11:46:00 INFO - PID 3823 | 11:46:00 INFO - PID 3823 | [#5] yui.html Cycles:180 Average:24271.42 Median:20150.20 stddev:34147.49 (169.5%) stddev-sans-first:34198.10 11:46:00 INFO - PID 3823 | Values: 879.2 901.9 912.5 920.2 937.9 19149.2 19944.9 19964.6 20051.8 20149.8 17327.1 17455.8 17478.8 17480.1 17480.9 948.7 949.1 951.7 954.3 957.1 976.3 979.9 981.0 981.1 981.4 998.3 1023.7 1025.2 1028.8 1032.1 1009.6 1011.6 1011.7 1012.4 1019.0 734.1 746.9 747.5 747.6 750.0 19357.8 20292.9 20437.8 20486.1 20530.6 21160.3 21315.6 21706.3 21717.2 21727.0 16841.5 17128.6 17178.8 17234.2 17343.2 3725.4 3739.8 3740.8 3764.2 3769.4 124510.0 134190.0 136099.3 136808.0 137946.0 17482.2 17748.6 17892.1 17911.0 17933.3 31207.4 31229.2 31268.0 31400.8 31449.8 9124.5 9240.1 9269.4 9361.0 9487.7 4393.2 4481.3 4495.7 4509.9 4511.1 5554.8 5572.3 5674.2 5696.2 5703.9 1077.7 1113.4 1122.6 1129.0 1130.4 685.8 699.9 700.2 708.9 710.1 11383.6 11790.6 11943.6 11978.7 11997.2 664.9 675.6 678.7 691.6 699.9 5184.4 5191.9 5201.5 5389.4 5437.3 114696.0 115818.0 120914.4 121670.0 124385.0 77957.6 102169.5 106599.5 111419.0 112172.0 93266.5 94786.0 95062.5 97755.0 98198.5 12262.4 12823.9 12831.7 12878.0 12922.9 42793.8 42817.4 44205.0 44583.6 44766.6 58154.0 58251.0 59322.4 59431.7 59795.0 17453.4 17711.0 17932.6 18027.8 18029.9 20005.6 20033.9 20150.6 20484.6 20558.7 15931.8 16094.4 16118.3 16198.9 16441.8 18855.5 18934.0 19090.2 19130.0 19343.0 15064.5 15066.2 15100.7 15148.7 15840.7 11937.9 12111.6 12123.5 12350.3 12508.8 12217.7 12324.9 12577.9 12606.9 12711.8 11:46:00 INFO - PID 3823 | -------- Summary: end -------- 11:46:00 INFO - PID 3823 | 11:46:01 INFO - TEST-INFO | 3823: exit 0 11:46:01 INFO - TEST-OK | dromaeo_css | took 1117817ms 11:46:01 INFO - TEST-START | kraken 11:46:01 INFO - Initialising browser for kraken test... 11:46:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36100/getInfo.html -profile /tmp/tmpHBnjmQ/profile 11:46:01 INFO - TEST-INFO | started process 4213 (/builds/slave/test/build/application/firefox/firefox http://localhost:36100/getInfo.html) 11:46:08 INFO - TEST-INFO | 4213: exit 0 11:46:08 INFO - Browser initialized. 11:46:08 INFO - Running cycle 1/1 for kraken test... 11:46:08 INFO - Using env: {'DISPLAY': ':0', 11:46:08 INFO - 'HOME': '/home/cltbld', 11:46:08 INFO - 'JSGC_DISABLE_POISONING': '1', 11:46:08 INFO - 'LANG': 'en_US.UTF-8', 11:46:08 INFO - 'LANGUAGE': 'en_US:en', 11:46:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:46:08 INFO - 'LOGNAME': 'cltbld', 11:46:08 INFO - 'MAIL': '/var/mail/cltbld', 11:46:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:46:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:46:08 INFO - 'MOZ_ACCELERATED': '1', 11:46:08 INFO - 'MOZ_CRASHREPORTER': '1', 11:46:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:46:08 INFO - 'MOZ_NO_REMOTE': '1', 11:46:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:46:08 INFO - 'MOZ_WEBRENDER': '1', 11:46:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:46:08 INFO - 'NO_EM_RESTART': '1', 11:46:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:46:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:08 INFO - 'PWD': '/builds/slave/test', 11:46:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:46:08 INFO - 'RUST_BACKTRACE': 'full', 11:46:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:46:08 INFO - 'SHELL': '/bin/bash', 11:46:08 INFO - 'SHLVL': '1', 11:46:08 INFO - 'TERM': 'linux', 11:46:08 INFO - 'TMOUT': '86400', 11:46:08 INFO - 'USER': 'cltbld', 11:46:08 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135', 11:46:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:08 INFO - '_': '/tools/buildbot/bin/python'} 11:46:08 INFO - TEST-INFO | started process 4458 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHBnjmQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:46:09 INFO - PID 4458 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:46:09 INFO - PID 4458 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 11:46:09 INFO - PID 4458 | 11:46:09 INFO - PID 4458 | (/builds/slave/test/build/application/firefox/firefox:4518): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:46:09 INFO - PID 4458 | 11:46:20 INFO - PID 4458 | RSS: Main: 190459904 11:46:20 INFO - PID 4458 | 11:46:22 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36100/tests/kraken/driver.html?testName=audio-beat-detection) 11:46:23 INFO - PID 4458 | RSS: Main: 189022208 11:46:23 INFO - PID 4458 | 11:46:26 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36100/tests/kraken/driver.html?testName=audio-dft) 11:46:27 INFO - PID 4458 | RSS: Main: 189399040 11:46:27 INFO - PID 4458 | 11:46:30 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36100/tests/kraken/driver.html?testName=audio-fft) 11:46:30 INFO - PID 4458 | RSS: Main: 186474496 11:46:30 INFO - PID 4458 | 11:46:32 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36100/tests/kraken/driver.html?testName=audio-oscillator) 11:46:33 INFO - PID 4458 | RSS: Main: 187723776 11:46:33 INFO - PID 4458 | 11:46:35 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36100/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:46:36 INFO - PID 4458 | RSS: Main: 187817984 11:46:36 INFO - PID 4458 | 11:46:39 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36100/tests/kraken/driver.html?testName=imaging-darkroom) 11:46:40 INFO - PID 4458 | RSS: Main: 190087168 11:46:40 INFO - PID 4458 | 11:46:44 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36100/tests/kraken/driver.html?testName=imaging-desaturate) 11:46:44 INFO - PID 4458 | RSS: Main: 188399616 11:46:44 INFO - PID 4458 | 11:46:47 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36100/tests/kraken/driver.html?testName=json-parse-financial) 11:46:48 INFO - PID 4458 | RSS: Main: 189140992 11:46:48 INFO - PID 4458 | 11:46:49 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36100/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:46:50 INFO - PID 4458 | RSS: Main: 189816832 11:46:50 INFO - PID 4458 | 11:46:52 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36100/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:46:52 INFO - PID 4458 | RSS: Main: 190046208 11:46:52 INFO - PID 4458 | 11:46:55 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36100/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:46:55 INFO - PID 4458 | RSS: Main: 190029824 11:46:55 INFO - PID 4458 | 11:46:58 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36100/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:46:58 INFO - PID 4458 | RSS: Main: 191016960 11:46:58 INFO - PID 4458 | 11:47:02 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36100/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:47:02 INFO - PID 4458 | RSS: Main: 190590976 11:47:02 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | Cycle 1(1): loaded http://localhost:36100/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36100/tests/kraken/driver.html?testName=ai-astar) 11:47:04 INFO - PID 4458 | RSS: Main: 191176704 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | __start_tp_report 11:47:04 INFO - PID 4458 | _x_x_mozilla_page_load 11:47:04 INFO - PID 4458 | _x_x_mozilla_page_load_details 11:47:04 INFO - PID 4458 | |i|pagename|runs| 11:47:04 INFO - PID 4458 | |0;ai-astar;111;115;108;110;112;106;107;116;109;119 11:47:04 INFO - PID 4458 | |1;audio-beat-detection;130;114;120;117;116;147;131;128;117;149 11:47:04 INFO - PID 4458 | |2;audio-dft;170;163;170;162;170;172;168;167;169;164 11:47:04 INFO - PID 4458 | |3;audio-fft;78;77;74;73;79;76;80;75;77;83 11:47:04 INFO - PID 4458 | |4;audio-oscillator;79;86;86;83;80;79;79;86;79;129 11:47:04 INFO - PID 4458 | |5;imaging-gaussian-blur;116;114;116;119;119;123;117;113;116;114 11:47:04 INFO - PID 4458 | |6;imaging-darkroom;154;149;150;149;152;158;154;150;149;148 11:47:04 INFO - PID 4458 | |7;imaging-desaturate;99;95;94;98;95;96;95;94;97;95 11:47:04 INFO - PID 4458 | |8;json-parse-financial;54;62;60;54;58;56;55;56;55;54 11:47:04 INFO - PID 4458 | |9;json-stringify-tinderbox;47;41;41;46;39;38;41;38;38;39 11:47:04 INFO - PID 4458 | |10;stanford-crypto-aes;73;75;79;75;81;80;80;77;79;81 11:47:04 INFO - PID 4458 | |11;stanford-crypto-ccm;111;115;125;118;122;116;113;108;114;111 11:47:04 INFO - PID 4458 | |12;stanford-crypto-pbkdf2;175;175;176;169;188;173;182;178;169;174 11:47:04 INFO - PID 4458 | |13;stanford-crypto-sha256-iterative;66;66;66;66;65;67;66;68;76;69 11:47:04 INFO - PID 4458 | __end_tp_report 11:47:04 INFO - PID 4458 | __start_cc_report 11:47:04 INFO - PID 4458 | _x_x_mozilla_cycle_collect,759 11:47:04 INFO - PID 4458 | __end_cc_report 11:47:04 INFO - PID 4458 | __startTimestamp1506192424734__endTimestamp 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | ------- Summary: start ------- 11:47:04 INFO - PID 4458 | Number of tests: 14 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:4.22 (3.8%) stddev-sans-first:4.47 11:47:04 INFO - PID 4458 | Values: 111.0 115.0 108.0 110.0 112.0 106.0 107.0 116.0 109.0 119.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#1] audio-beat-detection Cycles:10 Average:126.90 Median:129.00 stddev:12.69 (9.8%) stddev-sans-first:13.41 11:47:04 INFO - PID 4458 | Values: 130.0 114.0 120.0 117.0 116.0 147.0 131.0 128.0 117.0 149.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#2] audio-dft Cycles:10 Average:167.50 Median:169.50 stddev:3.41 (2.0%) stddev-sans-first:3.49 11:47:04 INFO - PID 4458 | Values: 170.0 163.0 170.0 162.0 170.0 172.0 168.0 167.0 169.0 164.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#3] audio-fft Cycles:10 Average:77.20 Median:77.50 stddev:2.97 (3.8%) stddev-sans-first:3.14 11:47:04 INFO - PID 4458 | Values: 78.0 77.0 74.0 73.0 79.0 76.0 80.0 75.0 77.0 83.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:81.50 stddev:15.23 (18.7%) stddev-sans-first:15.90 11:47:04 INFO - PID 4458 | Values: 79.0 86.0 86.0 83.0 80.0 79.0 79.0 86.0 79.0 129.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:116.50 stddev:2.98 (2.6%) stddev-sans-first:3.15 11:47:04 INFO - PID 4458 | Values: 116.0 114.0 116.0 119.0 119.0 123.0 117.0 113.0 116.0 114.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#6] imaging-darkroom Cycles:10 Average:151.30 Median:151.00 stddev:3.16 (2.1%) stddev-sans-first:3.20 11:47:04 INFO - PID 4458 | Values: 154.0 149.0 150.0 149.0 152.0 158.0 154.0 150.0 149.0 148.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:95.50 stddev:1.69 (1.8%) stddev-sans-first:1.33 11:47:04 INFO - PID 4458 | Values: 99.0 95.0 94.0 98.0 95.0 96.0 95.0 94.0 97.0 95.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.00 stddev:2.76 (4.9%) stddev-sans-first:2.78 11:47:04 INFO - PID 4458 | Values: 54.0 62.0 60.0 54.0 58.0 56.0 55.0 56.0 55.0 54.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#9] json-stringify-tinderbox Cycles:10 Average:40.80 Median:41.00 stddev:3.26 (7.9%) stddev-sans-first:2.57 11:47:04 INFO - PID 4458 | Values: 47.0 41.0 41.0 46.0 39.0 38.0 41.0 38.0 38.0 39.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:79.50 stddev:2.83 (3.6%) stddev-sans-first:2.35 11:47:04 INFO - PID 4458 | Values: 73.0 75.0 79.0 75.0 81.0 80.0 80.0 77.0 79.0 81.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#11] stanford-crypto-ccm Cycles:10 Average:115.30 Median:115.50 stddev:5.21 (4.5%) stddev-sans-first:5.29 11:47:04 INFO - PID 4458 | Values: 111.0 115.0 125.0 118.0 122.0 116.0 113.0 108.0 114.0 111.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.90 Median:175.50 stddev:5.74 (3.3%) stddev-sans-first:6.08 11:47:04 INFO - PID 4458 | Values: 175.0 175.0 176.0 169.0 188.0 173.0 182.0 178.0 169.0 174.0 11:47:04 INFO - PID 4458 | 11:47:04 INFO - PID 4458 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:66.50 stddev:3.21 (4.8%) stddev-sans-first:3.35 11:47:04 INFO - PID 4458 | Values: 66.0 66.0 66.0 66.0 65.0 67.0 66.0 68.0 76.0 69.0 11:47:04 INFO - PID 4458 | -------- Summary: end -------- 11:47:04 INFO - PID 4458 | 11:47:05 INFO - PID 4458 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:47:05 INFO - TEST-INFO | 4458: exit 0 11:47:06 INFO - TEST-OK | kraken | took 64646ms 11:47:06 INFO - SUITE-END | took 1182s 11:47:06 INFO - Completed test suite (00:19:42) 11:47:06 INFO - javascript benchmark 11:47:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8517.528462611184, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3082.7071428214317, 3102.4880894652397, 3120.687931206879, 3120.8751649934, 3129.31155145868, 6199.225096862892, 6307.117003619491, 6322.589031712935, 6366.108744775729, 6415.71129299182, 6209.565330426872, 6284.622922624634, 6299.118123462719, 6302.653354065525, 6313.936860631392, 5758.769649214037, 5782.392848750884, 5844.736986835582, 5846.0354041583305, 5846.356900740915, 5855.824325270249, 5916.0, 5916.556258280624, 5934.465898069173, 5956.315023772257, 5748.65508069513, 5830.542364408898, 5840.0, 5884.146798714184, 5942.108683697436, 5463.289772329612, 5609.130584759328, 5722.628029178131, 5821.592488525805, 5865.178874957509, 5747.942520574783, 5754.827355179351, 5828.825135245949, 5887.116932460122, 5896.734646940855, 6141.477974372184, 6235.719392627298, 6374.458171055466, 6393.584417012879, 6404.615723056585, 6757.290484499106, 6845.178578570553, 6871.622060786654, 6883.965580172117, 6886.139232595926, 6804.353586409283, 6809.421199198074, 6837.111175547197, 6885.380315771605, 6925.995730619032, 6131.63210207389, 6257.8748425031245, 6312.116303717483, 6376.776812811576, 6435.613863168225, 6952.130983627047, 7013.544119632207, 7079.433645308363, 7094.574325540484, 7096.112985876765, 6451.483881289486, 6549.083128362033, 6576.0, 6579.2104947406615, 6613.537052406382, 6666.600003999775, 6671.86656266872, 6693.397594216543, 6712.026756120335, 6717.193936727624, 6605.438537724347, 6645.66771661415, 6654.434373078246, 6656.0, 6661.4004739573675, 6721.059051732761, 6732.124823772878, 6841.179058512911, 6844.828879277959, 6854.862902741917, 5297.735113244323, 5313.362396512443, 5315.255864178941, 5398.163284690879, 5413.566914646819, 4592.770361481913, 4620.306953956933, 4664.836730714409, 4672.415948006499, 4707.7881495332795, 5662.546996240373, 5741.5119714824705, 5784.797532086441, 5798.710064496759, 5828.766849326064, 6685.264620891698, 6711.932880671228, 6743.865122697617, 6748.0, 6759.155105611799, 5755.194272801811, 5772.942270577324, 5833.82498525045, 5875.030619947661, 5877.206577112121, 5855.824325270163, 5917.260342457193, 5928.940710592925, 5931.199288096138, 5937.198478205473, 6533.444657204096, 6555.789432075292, 6634.03806448072, 6657.966710166515, 6672.432843208382, 6652.634105124264, 6762.053312536151, 6820.590764554143, 6918.792436226921, 6926.896096558455, 6431.903521447276, 6541.0, 6604.867902642017, 6622.337766223339, 6639.767608133692, 6753.1220941277315, 6758.0, 6785.660716964231, 6788.0, 6818.249992500721, 6756.290589488033, 6850.486213533905, 6922.169339679271, 6933.479989000744, 6940.201876784033, 6468.773592924225, 6582.76960306387, 6583.63789991555, 6606.6366349849295, 6697.765578204934, 6525.8694826104165, 6529.085927970183, 6533.934660653333, 6546.607203567801, 6550.574212676161, 6406.13517175194, 6478.2550006749825, 6480.027995800479, 6513.837154071186, 6524.6411447368955, 6582.17722784652, 6612.206535215805, 6635.502337324817, 6686.933130668631, 6746.291639377991, 6256.718447669775, 6367.9044814328745, 6382.840428989127, 6485.156929599122, 6514.055461958084], "value": 6165.503316918356, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [868.9131086891311, 875.7504111328273, 885.07067579042, 910.508325504228, 911.2436677557628, 40641.79679101608, 41019.79490102549, 41152.38271425931, 41465.17069658605, 41493.17013659725, 37472.0, 40994.0, 40996.18007639846, 41153.0, 41212.0, 7794.688212471494, 7986.440949133563, 7996.400269979746, 8018.238267364591, 8022.277994980451, 7876.448648594601, 7913.604319784016, 7993.640286187136, 8026.398020148483, 8036.799080023017, 7987.400944929124, 7994.440389172761, 8015.158408367125, 8044.758657240262, 8063.435559510836, 7965.840683186362, 7971.800704982364, 7978.521288722666, 7983.3214176795045, 8036.035675718893, 7929.762107136795, 8004.63979120941, 8005.0794158671915, 8009.279164875189, 8013.358931285541, 37718.811405943066, 38082.42876356857, 38205.42691859624, 38207.0, 38254.80872595647, 3434.3131373725105, 3468.6357932416986, 3473.8263086845554, 3480.443129099357, 3483.494893240466, 30857.0, 30997.0, 31041.37917241665, 31046.84476577625, 31226.843865780527, 4997.150484417622, 5021.0, 5023.0, 5096.541311281965, 5165.302684137631, 184682.0, 184704.0764796181, 186269.99999999863, 186529.0, 186621.0, 46536.5346346539, 46728.766356168, 47098.76450617793, 47264.7636761814, 47287.29069063967, 49529.0, 49746.25380619294, 49849.0, 49881.25178122331, 52997.47002530003, 14092.929535352258, 14366.425342986164, 14422.711545769025, 14454.855451445352, 14499.637509062359, 11465.254758440675, 11577.0, 11600.593979210857, 11619.128565357632, 11649.767004660029, 14132.929335353258, 14291.785623215661, 14349.78026867728, 14419.639509012359, 14479.927600362142, 5508.807191748269, 5615.2700148981185, 5646.20953066571, 5771.365149833515, 5776.595638305278, 957.7174733453647, 967.6419724701906, 998.8851282102506, 1004.7689031522791, 1016.1159790981745, 22113.0, 22532.436689082904, 22581.322560323217, 22614.208502702328, 22762.886185568965, 1088.2436706488913, 1093.578972095749, 1093.912487001054, 1094.1247002398209, 1097.720081379243, 11920.225185363097, 11964.162508624313, 12002.279863208236, 12011.459484323226, 12145.939270303592, 230818.0, 231647.0, 232307.0, 232387.0, 233438.0, 43575.34636980448, 43866.56133438625, 49779.50220497821, 49782.75108624506, 50150.247746283836, 1385.9653508662163, 1390.4994202087444, 1395.797609346639, 1402.4320150339129, 1414.5332040426636, 26212.606810898233, 26833.195004149908, 26982.247343072522, 27049.59425608657, 27221.0, 37277.6272237285, 37492.06269843276, 37495.43756843595, 37540.0, 37571.81214093912, 197750.0, 198033.0, 198502.0, 200354.0, 200460.0, 34956.30087398187, 35023.124421888635, 35073.0, 35100.297994039465, 35666.64333356633, 41353.0, 43007.78496107499, 43037.139257215305, 43244.56755432531, 43767.56232437635, 30822.69177308288, 31102.844485777427, 31139.532907006866, 31163.688363116078, 31315.37369252648, 30648.846755766077, 30730.0, 30851.537226941164, 31335.21661958378, 31406.842965785938, 35341.469877951335, 35352.0, 35738.0, 35958.64041359553, 36115.81942090273, 30275.545866812456, 30721.231969200948, 30783.846080769454, 30946.845265774427, 31218.531722024643, 19737.0, 19998.800011999694, 20072.69890951666, 20338.186472540932, 20476.180952761722], "value": 16935.626188643717, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.7536600891149, 601.3835818286256, 605.4460168945413, 610.4749915073039, 618.5793660310989, 21337.25319613814, 21983.340499785012, 22121.557568848613, 22129.33611991639, 22132.8893355533, 21592.56814863702, 23714.17000404986, 24189.0, 24457.75542244577, 24532.264032079023, 6527.1188389567515, 6561.901571476432, 6692.464602831762, 6692.9330706693045, 6693.129893113888, 6586.012098185262, 6621.503387245952, 6665.100211471462, 6668.799936001928, 6670.966645166792, 6296.590721603081, 6515.0, 6581.506387020969, 6588.143541339619, 6652.467802575807, 6461.80614581561, 6500.642464664442, 6556.803295901131, 6575.243846957614, 6585.209774827052, 6032.366601506867, 6073.817785466443, 6088.726007329681, 6099.847503812396, 6171.166892469504, 26994.865025674942, 27605.171844844688, 27744.861275693493, 27767.722322776717, 27863.885444582193, 1954.4332143678316, 1961.3723608445302, 1965.5675751334684, 1974.6544354738007, 1976.0613708488497, 18209.817901820945, 18858.811411885843, 19403.126859291224, 19436.902815485973, 19472.0, 2408.8193385496156, 2437.549053425122, 2443.584590619599, 2456.4841383309526, 2465.149523414419, 123809.38095309556, 129931.0, 130327.0, 134078.0, 139423.0, 14353.823908710807, 14663.353590994871, 14688.50295449776, 14762.778558321634, 14834.332455039548, 22754.886225568767, 22920.08319667228, 22980.425489362566, 23033.07867685274, 23145.768542314698, 9151.77120571991, 9207.723768286962, 9249.768755781024, 9290.767730806649, 9485.146336829719, 8356.874646880302, 8367.079621241659, 8399.664013439395, 8433.409661323649, 8555.743327700178, 9128.361014728906, 9213.953930230306, 9223.631054757869, 9238.722838314861, 9420.434773913586, 2811.536096544088, 2842.5167721486982, 2874.094660182036, 2882.3514709190263, 2901.3762041161253, 940.9294302927308, 963.1283688261994, 977.6627063663025, 978.9706308810747, 980.887197972228, 18100.36648717315, 18507.074646267632, 18551.90724046398, 18567.25730970773, 18746.250149993848, 1003.4882210072915, 1004.542932965499, 1010.4543546484963, 1021.070825548747, 1021.1218352217087, 10278.691639250834, 10321.74195645115, 10368.0, 10512.632057877938, 10644.09525190352, 437150.0, 451353.0, 451685.0, 455136.0, 458687.0, 102280.0, 105809.0, 105963.0, 107159.46420268004, 107533.4623326894, 1037.8806437259811, 1112.3103675720943, 1143.0398465289197, 1174.2308787744041, 1181.6986668399504, 19385.806141938963, 19608.60782784308, 19897.0, 20045.398638040882, 20062.79937200609, 54000.0, 54623.726881365335, 54832.451675482735, 55155.44844551664, 55338.0, 63770.68114659397, 65837.34162658312, 66233.33766662271, 66772.3322766766, 67151.0, 13937.0, 14140.222287774272, 14168.716625667636, 14211.076280041763, 14287.142771433748, 22120.557588848456, 22171.334859954226, 22291.554168916857, 22323.44191395228, 22415.551688966454, 17304.307827686753, 17724.46826595204, 17776.644467110327, 17808.3767068152, 18044.90977545104, 15669.0, 15749.0, 15944.760828587348, 16133.193340333171, 16639.833601663828, 16941.32234710598, 17971.910140449214, 18103.72844407361, 18207.45377638673, 18267.90866045661, 13912.0, 13942.442302307794, 13968.161910285009, 13978.720425591227, 13984.160950343012, 17223.311067557657, 17516.299348026445, 17574.38489652907, 17643.382481613597, 17807.59597454379], "value": 11644.591750423611, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.8250427444432, 1391.0262816028783, 1401.1242973141793, 1412.773956167012, 1425.0309789343248, 15147.621309467255, 15736.921315393436, 15809.288582013809, 15897.523074307775, 15901.68196636068, 15394.076355418683, 15422.305996230169, 15528.301226444808, 15544.68910621787, 15566.45517406891, 2806.144126041567, 2815.338395477061, 2836.375997280595, 2871.798974071816, 2872.454233695591, 2747.3955729739423, 2802.6636803583597, 2815.4650616382824, 2819.3233623930314, 2822.0969289827162, 2733.8381187995124, 2802.1033269353816, 2836.6170566973506, 2838.3046153692376, 2850.087971849009, 2818.985905070482, 2840.4603125406106, 2858.19970408286, 2870.5981162637145, 2875.7555607773365, 2688.5832695932163, 2713.498002869456, 2724.3189202699327, 2727.904523341693, 2741.040635777482, 15128.848711512854, 15212.923935380251, 15406.152661603608, 15463.536093917088, 15493.535193944199, 7530.171681115073, 7837.6864925402915, 7914.5251284922515, 7925.405594580429, 7928.7621371358955, 5521.0, 5622.606417550773, 5626.606137570372, 5689.687067211302, 5733.569982251306, 1969.33042765459, 1996.46095554201, 2016.2842191022123, 2037.663785475395, 2039.5207126325301, 27081.32296692579, 27130.5930411044, 27260.31849203766, 27299.0, 27310.86344568304, 6586.473082153416, 6782.287859774751, 6803.387695107464, 7030.402415794612, 7074.186468556079, 17603.0, 17605.295788168587, 17630.559236019202, 17657.911710441367, 17681.38115165963, 8056.476329038594, 8182.7954301142945, 8221.424500284922, 8244.093149753642, 8271.090180080308, 7170.103737032871, 7282.725523033554, 7300.758870991944, 7384.704611815575, 7572.621368931642, 5298.682079075268, 5425.538829199562, 5426.62013659048, 5499.752511137009, 5540.252065971123, 1430.6924011337612, 1574.126359870283, 1581.549258461333, 1596.0503500417233, 1596.9201539922958, 689.4932224814758, 872.7556284240422, 882.911708829112, 895.7581453007651, 896.8834051573253, 8402.907891778534, 8441.409101362968, 8528.914710852936, 8571.571421428904, 8580.2706769924, 967.6758285974137, 1000.5097502223858, 1000.7698229407277, 1007.6473234367912, 1017.9287449878585, 5123.308353372322, 5138.434772175057, 5156.8968620627375, 5210.270562121306, 5331.040412725747, 21389.46526336854, 21696.24063157782, 22337.664935025987, 22493.55012899733, 22538.098476061103, 21635.0, 21777.128914843546, 22162.0, 22235.221767238392, 22325.776742232694, 11306.378149201708, 11712.648620541397, 11760.529578816922, 11772.70568235818, 11806.46870890812, 7962.124166341813, 8091.83816323682, 8121.106678265504, 8137.471064380797, 8188.83622327562, 18334.358297459527, 18754.0, 18793.436196914114, 18916.621667566847, 19032.33386831454, 19552.706709399656, 19733.112009959874, 19913.900430497753, 20030.39908802738, 20283.188672453525, 9870.901290986998, 11061.889381106086, 11073.94463027712, 11075.224734268524, 11089.778204435705, 14232.430702771773, 14286.785698214742, 14455.0, 14457.132572045712, 14461.638459038608, 9729.756756081155, 9742.853857192005, 10630.0, 11202.66392008241, 11332.320060796379, 11067.114630829676, 11219.60731374398, 11251.7749645005, 11260.493277802683, 11433.371164586028, 9879.160271377013, 9942.801143977225, 9977.987531259041, 9999.35004224723, 10035.197184225099, 6836.692348844214, 6898.241193468814, 6916.377526022682, 6926.619035952869, 6955.8956615649795, 6870.3473170048765, 6963.61700106499, 6982.546134501445, 6993.090898183407, 7054.0], "value": 6762.8253680625085, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.2183498330064, 542.574079347712, 544.2326319888957, 548.9011977843986, 551.4292707048205, 6289.5597308188335, 6308.4322410983, 6330.018847078704, 6357.61854288743, 6390.105385246064, 6225.844353891143, 6252.718627661755, 6269.435750782429, 6274.4039316265, 6327.5570710050315, 5212.739363031852, 5223.373195216579, 5245.452591485521, 5275.261463395128, 5308.893822123555, 5225.320708307933, 5246.822568634012, 5264.0, 5281.70950597717, 5303.390110137326, 4821.421429428474, 5001.0, 5011.6695736482525, 5052.797888084472, 5053.444121146671, 4975.203967365241, 5015.523525265094, 5054.823081192178, 5056.620753443488, 5057.873553161164, 4430.379746835446, 4567.474740404829, 4594.448666160049, 4598.379218805485, 4601.953980460219, 6082.330943596201, 6140.263168419773, 6168.920438923171, 6182.289036760785, 6216.937830621681, 5772.855678608026, 5773.653580785166, 5793.246877905845, 5833.037548804442, 5836.824895253107, 6397.880370935114, 6575.0, 6637.668116594151, 6649.437382215224, 6668.166479190101, 4911.4106307243, 4939.876503087416, 4969.726665033421, 4977.527134922213, 4990.30135780991, 6572.619749852537, 6730.596164230162, 6742.662866856637, 6768.796936091925, 6789.490788190906, 5065.417476990119, 5079.755459912345, 5081.441041485434, 5083.38999320084, 5107.7446127693465, 5122.129238029544, 5142.2029585413575, 5183.948160518422, 5189.273501709764, 5191.299174611379, 5759.625624334405, 5765.164051212634, 5776.451237132465, 5785.553611597101, 5809.3609702932645, 6630.115899583697, 6643.2028156620545, 6651.501137414713, 6660.0, 6670.299618540081, 1768.9563157736998, 1773.796013458469, 1779.510634575502, 1783.3758184635533, 1794.0312231395162, 1172.3786393211612, 1235.7281398092405, 1250.6310638361706, 1253.2417887178126, 1263.4819723913151, 5509.586780991442, 5668.744906479218, 5679.5172410345585, 5704.172894930211, 5731.484166425042, 6565.770198043046, 6626.801195964129, 6655.833604159935, 6713.26154123046, 6717.6977036033495, 5621.353544342372, 5663.801766938137, 5700.118960742945, 5745.540356771448, 5749.942500574941, 3469.8959031229106, 3500.64993500653, 3509.2104276537575, 3541.7166626669805, 3547.52108465359, 6237.6277219011745, 6316.30520642729, 6368.617882927039, 6368.968155159195, 6390.9360906391275, 6276.717547710364, 6880.759173428906, 6891.416463438984, 6900.482463815234, 6924.965375173092, 6257.812265632129, 6401.167848179707, 6552.951527755632, 6555.901661475082, 6573.638449885302, 6494.74021039172, 6591.725854580162, 6601.3728695773825, 6652.866942661023, 6687.786694382702, 6614.272430032596, 6619.371159739817, 6656.7670131545165, 6680.665966701743, 6732.461403087644, 6398.936010639834, 6524.934750652433, 6538.574992625464, 6539.967300163468, 6552.3447655234095, 5082.898342033212, 5083.694978301166, 5108.821191258288, 5118.283440318284, 5118.820841270538, 6356.2054743157105, 6568.113304703804, 6611.537192396486, 6633.270340262664, 6655.0, 6355.491560675043, 6479.611223326616, 6554.934450655433, 6628.867422651617, 6631.63526006055, 6430.582012169194, 6469.385408386196, 6489.675516224075, 6507.316731743288, 6590.505712071518, 6418.390252925965, 6424.582402143846, 6460.45086167681, 6471.870562588816, 6581.3418658135715, 6531.673416329259, 6594.769183078761, 6616.0, 6619.054520738226, 6619.238787539494, 6277.937220627735, 6349.904751428825, 6358.745650173942, 6385.0, 6420.582662126947], "value": 5088.2894725403785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [879.156010230179, 901.9368644194909, 912.4570880326207, 920.2499962530537, 937.9249660027199, 19149.234030638774, 19944.900275498603, 19964.60070798585, 20051.799482005175, 20149.798502014975, 17327.133643317848, 17455.82544174558, 17478.825211747877, 17480.125993700294, 17480.912595437036, 948.7248697877607, 949.1268033409245, 951.7144856543044, 954.2938225712957, 957.1433566957569, 976.2726768557435, 979.9118079372855, 980.9901900981006, 981.087588542655, 981.4258658684678, 998.3161534348994, 1023.6775415744054, 1025.2003437318865, 1028.7633844215798, 1032.0659802878406, 1009.6466236817055, 1011.5801942193981, 1011.6610935336671, 1012.4330374989993, 1018.9745256368614, 734.100726609904, 746.902902622661, 747.4879707400448, 747.6075060593178, 749.9962500187519, 19357.806421935882, 20292.898535507375, 20437.795622043737, 20486.078126484343, 20530.589388212302, 21160.259390921245, 21315.573688526143, 21706.34880953574, 21717.2398966037, 21727.0, 16841.494755157244, 17128.57178570546, 17178.828211717973, 17234.224459899207, 17343.21955512005, 3725.3853114236117, 3739.7943113128767, 3740.812959352049, 3764.171882185915, 3769.4157405602177, 124510.0, 134190.0, 136099.31950340184, 136808.0, 137946.0, 17482.213300401512, 17748.55628609269, 17892.10539473021, 17911.014894180684, 17933.282668693366, 31207.375852482823, 31229.21926951799, 31268.0, 31400.842995784875, 31449.842750786098, 9124.45253284805, 9240.122188392092, 9269.351145419756, 9360.953195234117, 9487.66793162236, 4393.1872603568445, 4481.305397663367, 4495.730256184639, 4509.864704058883, 4511.097780443924, 5554.805581804618, 5572.331320241516, 5674.205611214434, 5696.202531645573, 5703.857403564861, 1077.7413420778958, 1113.4432783608195, 1122.5846436818267, 1128.9830652540218, 1130.3895896215952, 685.7736946807541, 699.8600279944031, 700.1877821726772, 708.890122031086, 710.1159056974101, 11383.601573944872, 11790.646280611596, 11943.641690749291, 11978.700532486757, 11997.160198786001, 664.9335066493312, 675.5777638975641, 678.6708446403491, 691.5781373362245, 699.923008469068, 5184.377874655065, 5191.896162076738, 5201.53186213235, 5389.353277606712, 5437.34751829783, 114696.0, 115818.0, 120914.39542802406, 121670.0, 124385.0, 77957.57458253174, 102169.48915255377, 106599.46700266605, 111419.0, 112172.0, 93266.53366733258, 94786.0, 95062.52468737612, 97755.0, 98198.50900745594, 12262.4481898313, 12823.93588032054, 12831.743365132459, 12878.034147438791, 12922.901553368069, 42793.78603106965, 42817.35773963455, 44205.0, 44583.55416445924, 44766.55233447624, 58154.0, 58251.0, 59322.40677593169, 59431.70284148725, 59795.0, 17453.38913138034, 17711.0, 17932.551686207426, 18027.819721802614, 18029.909850450666, 20005.59988800245, 20033.899830500755, 20150.59698806045, 20484.590308194052, 20558.691619626017, 15931.840681593036, 16094.356225751308, 16118.274677639769, 16198.919005404898, 16441.83558164403, 18855.528611784815, 18934.0, 19090.23639054422, 19130.0, 19343.032848357245, 15064.472743453927, 15066.171360575274, 15100.697986040437, 15148.697026059637, 15840.683186336439, 11937.940310298392, 12111.636650900135, 12123.454444550192, 12350.320732359447, 12508.812367814307, 12217.69455763613, 12324.876751232372, 12577.87422125767, 12606.936965315115, 12711.80932286035], "value": 9125.631171272502, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1467.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 108.0, 110.0, 112.0, 106.0, 107.0, 116.0, 109.0, 119.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 114.0, 120.0, 117.0, 116.0, 147.0, 131.0, 128.0, 117.0, 149.0], "value": 126.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 163.0, 170.0, 162.0, 170.0, 172.0, 168.0, 167.0, 169.0, 164.0], "value": 167.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 74.0, 73.0, 79.0, 76.0, 80.0, 75.0, 77.0, 83.0], "value": 77.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 86.0, 86.0, 83.0, 80.0, 79.0, 79.0, 86.0, 79.0, 129.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 116.0, 119.0, 119.0, 123.0, 117.0, 113.0, 116.0, 114.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 149.0, 150.0, 149.0, 152.0, 158.0, 154.0, 150.0, 149.0, 148.0], "value": 151.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 95.0, 94.0, 98.0, 95.0, 96.0, 95.0, 94.0, 97.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 62.0, 60.0, 54.0, 58.0, 56.0, 55.0, 56.0, 55.0, 54.0], "value": 56.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 41.0, 41.0, 46.0, 39.0, 38.0, 41.0, 38.0, 38.0, 39.0], "value": 40.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 79.0, 75.0, 81.0, 80.0, 80.0, 77.0, 79.0, 81.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 125.0, 118.0, 122.0, 116.0, 113.0, 108.0, 114.0, 111.0], "value": 115.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 176.0, 169.0, 188.0, 173.0, 182.0, 178.0, 169.0, 174.0], "value": 175.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 65.0, 67.0, 66.0, 68.0, 76.0, 69.0], "value": 67.5, "unit": "score"}]}]} 11:47:06 INFO - Return code: 0 11:47:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:47:06 INFO - # TBPL SUCCESS # 11:47:06 INFO - Running post-action listener: _package_coverage_data 11:47:06 INFO - Running post-action listener: _resource_record_post_action 11:47:06 INFO - [mozharness: 2017-09-23 18:47:06.510151Z] Finished run-tests step (success) 11:47:06 INFO - Running post-run listener: _resource_record_post_run 11:47:06 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 14639104; Write bytes: 438415360; Read time: 5248; Write time: 706116 11:47:06 INFO - TinderboxPrint: CPU usage
13.3% 11:47:06 INFO - TinderboxPrint: I/O read bytes / time
14,639,104 / 5,248 11:47:06 INFO - TinderboxPrint: I/O write bytes / time
438,415,360 / 706,116 11:47:06 INFO - TinderboxPrint: CPU idle
8,302.3 (86.7%) 11:47:06 INFO - TinderboxPrint: CPU user
1,201.7 (12.5%) 11:47:06 INFO - TinderboxPrint: Swap in / out
0 / 0 11:47:06 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13500416; Read time: 0; Write time: 2140 11:47:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:47:06 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 12881920; Write bytes: 394211328; Read time: 4604; Write time: 679476 11:47:07 INFO - Running post-run listener: _upload_blobber_files 11:47:07 INFO - Blob upload gear active. 11:47:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:47:07 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 11:47:07 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'] 11:47:07 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 11:47:07 INFO - (blobuploader) - INFO - Open directory for files ... 11:47:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:47:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:47:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:08 INFO - (blobuploader) - INFO - Done attempting. 11:47:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:47:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:47:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:15 INFO - (blobuploader) - INFO - Done attempting. 11:47:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:47:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:47:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:16 INFO - (blobuploader) - INFO - Done attempting. 11:47:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:47:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:47:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:47:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:47:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:47:17 INFO - (blobuploader) - INFO - Done attempting. 11:47:17 INFO - (blobuploader) - INFO - Iteration through files over. 11:47:17 INFO - Return code: 0 11:47:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:47:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:47:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59ddfc4f64d9ce1fb11b1dd8ea991ef0356bad54af1337556095a183a283ce3bfce0f7bf71e21da1321ffbf482b48c1d2f592e336fcd2edb407fd6d289079f86", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9245aad50b3ad1067d648661f9c7789ec415de93e40d35484f0ca97cab0e2bf564f4b062d9f6039f8cc8a1ca7d6b2e2742b900b95245e22aee302040caf2ac39", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40291fb3f29745ecc9d9a749cb0997075f6c93091b6d72bc893518ad8b183b23e316b9d560dc49942a32c035b5f3f87ccc27671dae8304388579e5901d4b0a11", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07c09025e6d50cc9845e7c46e58c3f5a4e45d581fb2025822851451356a623d7b34351f232e1f9803362032e47cb419cfdda49d0702e982571d10a4e9e372103"} 11:47:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:47:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:47:17 INFO - Contents: 11:47:17 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59ddfc4f64d9ce1fb11b1dd8ea991ef0356bad54af1337556095a183a283ce3bfce0f7bf71e21da1321ffbf482b48c1d2f592e336fcd2edb407fd6d289079f86", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9245aad50b3ad1067d648661f9c7789ec415de93e40d35484f0ca97cab0e2bf564f4b062d9f6039f8cc8a1ca7d6b2e2742b900b95245e22aee302040caf2ac39", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40291fb3f29745ecc9d9a749cb0997075f6c93091b6d72bc893518ad8b183b23e316b9d560dc49942a32c035b5f3f87ccc27671dae8304388579e5901d4b0a11", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07c09025e6d50cc9845e7c46e58c3f5a4e45d581fb2025822851451356a623d7b34351f232e1f9803362032e47cb419cfdda49d0702e982571d10a4e9e372103"} 11:47:17 INFO - Running post-run listener: copy_logs_to_upload_dir 11:47:17 INFO - Copying logs to upload dir... 11:47:17 INFO - mkdir: /builds/slave/test/build/upload/logs 11:47:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.048352 ========= master_lag: -0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-09-23 11:47:17.566518) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 11:47:17.574387) ========= 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=df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59ddfc4f64d9ce1fb11b1dd8ea991ef0356bad54af1337556095a183a283ce3bfce0f7bf71e21da1321ffbf482b48c1d2f592e336fcd2edb407fd6d289079f86", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9245aad50b3ad1067d648661f9c7789ec415de93e40d35484f0ca97cab0e2bf564f4b062d9f6039f8cc8a1ca7d6b2e2742b900b95245e22aee302040caf2ac39", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40291fb3f29745ecc9d9a749cb0997075f6c93091b6d72bc893518ad8b183b23e316b9d560dc49942a32c035b5f3f87ccc27671dae8304388579e5901d4b0a11", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07c09025e6d50cc9845e7c46e58c3f5a4e45d581fb2025822851451356a623d7b34351f232e1f9803362032e47cb419cfdda49d0702e982571d10a4e9e372103"} build_url:https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/DNUXLEF0TY6Rkrc0rZZnLg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59ddfc4f64d9ce1fb11b1dd8ea991ef0356bad54af1337556095a183a283ce3bfce0f7bf71e21da1321ffbf482b48c1d2f592e336fcd2edb407fd6d289079f86", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9245aad50b3ad1067d648661f9c7789ec415de93e40d35484f0ca97cab0e2bf564f4b062d9f6039f8cc8a1ca7d6b2e2742b900b95245e22aee302040caf2ac39", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40291fb3f29745ecc9d9a749cb0997075f6c93091b6d72bc893518ad8b183b23e316b9d560dc49942a32c035b5f3f87ccc27671dae8304388579e5901d4b0a11", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/07c09025e6d50cc9845e7c46e58c3f5a4e45d581fb2025822851451356a623d7b34351f232e1f9803362032e47cb419cfdda49d0702e982571d10a4e9e372103"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 11:47:17.607958) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:47:17.608311) ========= 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=df17445c3590ff7897617a0e00000225-1506181140.575392-1563420135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004363 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 11:47:17.690952) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 11:47:17.691255) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 11:47:17.691563) ========= ========= Total master_lag: 0.10 =========