builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-018 starttime: 1505341430.28 results: success (0) revision: 0e706b53052c86b247bb8037d1a3fbf2e913045e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:50.282102) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:50.282522) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:50.282799) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:50.300716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:50.301138) ========= 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=62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 15:23:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 57.9K=0.2s 2017-09-13 15:23:50 (57.9 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.346049 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:50.664067) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:50.664412) ========= 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=62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017429 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:50.719350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-13 15:23:50.719714) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0e706b53052c86b247bb8037d1a3fbf2e913045e --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 0e706b53052c86b247bb8037d1a3fbf2e913045e --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=62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 15:23:50,792 truncating revision to first 12 chars 2017-09-13 15:23:50,792 Setting DEBUG logging. 2017-09-13 15:23:50,792 attempt 1/10 2017-09-13 15:23:50,792 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0e706b53052c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 15:23:51,474 unpacking tar archive at: mozilla-central-0e706b53052c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.005168 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-13 15:23:51.747044) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:51.750386) ========= script_repo_revision: 0e706b53052c86b247bb8037d1a3fbf2e913045e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:51.750777) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:51.751043) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 15:23:51.765481) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-13 15:23:51.765773) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:23:51 INFO - MultiFileLogger online at 20170913 15:23:51 in /builds/slave/test 15:23:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 15:23:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:23:51 INFO - {'append_to_log': False, 15:23:51 INFO - 'base_work_dir': '/builds/slave/test', 15:23:51 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:23:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:23:51 INFO - 'branch': 'Firefox-Non-PGO', 15:23:51 INFO - 'buildbot_json_path': 'buildprops.json', 15:23:51 INFO - 'code_coverage': False, 15:23:51 INFO - 'config_files': ('talos/linux_config.py',), 15:23:51 INFO - 'default_actions': ('clobber', 15:23:51 INFO - 'read-buildbot-config', 15:23:51 INFO - 'download-and-extract', 15:23:51 INFO - 'populate-webroot', 15:23:51 INFO - 'create-virtualenv', 15:23:51 INFO - 'install', 15:23:51 INFO - 'setup-mitmproxy', 15:23:51 INFO - 'run-tests'), 15:23:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:23:51 INFO - 'disable_ccov_upload': False, 15:23:51 INFO - 'disable_stylo': False, 15:23:51 INFO - 'download_minidump_stackwalk': True, 15:23:51 INFO - 'download_symbols': 'ondemand', 15:23:51 INFO - 'enable_stylo': False, 15:23:51 INFO - 'enable_webrender': False, 15:23:51 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:23:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:23:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:23:51 INFO - 'gecko_profile': False, 15:23:51 INFO - 'gecko_profile_interval': 0, 15:23:51 INFO - 'installer_path': 'installer.exe', 15:23:51 INFO - 'log_level': 'info', 15:23:51 INFO - 'log_name': 'talos', 15:23:51 INFO - 'log_to_console': True, 15:23:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:23:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:23:51 INFO - 'opt_config_files': (), 15:23:51 INFO - 'pip_index': False, 15:23:51 INFO - 'suite': 'dromaeojs-e10s', 15:23:51 INFO - 'system_bits': '32', 15:23:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:23:51 INFO - 'title': 'talos-linux64-ix-018', 15:23:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:23:51 INFO - 'use_talos_json': True, 15:23:51 INFO - 'verify': 'False', 15:23:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:23:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:23:51 INFO - 'work_dir': 'build'} 15:23:51 INFO - [mozharness: 2017-09-13 22:23:51.974257Z] Running clobber step. 15:23:51 INFO - Running pre-action listener: _resource_record_pre_action 15:23:51 INFO - Running main action method: clobber 15:23:51 INFO - rmtree: /builds/slave/test/build 15:23:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:23:53 INFO - Running post-action listener: _resource_record_post_action 15:23:53 INFO - [mozharness: 2017-09-13 22:23:53.518791Z] Finished clobber step (success) 15:23:53 INFO - [mozharness: 2017-09-13 22:23:53.518888Z] Running read-buildbot-config step. 15:23:53 INFO - Running pre-action listener: _resource_record_pre_action 15:23:53 INFO - Running main action method: read_buildbot_config 15:23:53 INFO - Using buildbot properties: 15:23:53 INFO - { 15:23:53 INFO - "project": "", 15:23:53 INFO - "product": "firefox", 15:23:53 INFO - "who": "kwierso@gmail.com", 15:23:53 INFO - "installer_path": "public/build/target.tar.bz2", 15:23:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:23:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:23:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:23:53 INFO - "stage_platform": "linux64", 15:23:53 INFO - "basedir": "/builds/slave/test", 15:23:53 INFO - "buildnumber": 818, 15:23:53 INFO - "platform": "ubuntu64_hw", 15:23:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:23:53 INFO - "slavebuilddir": "test", 15:23:53 INFO - "taskId": "JhpfO-MQRZShFVt03MyVUA", 15:23:53 INFO - "branch": "mozilla-central", 15:23:53 INFO - "script_repo_revision": "production", 15:23:53 INFO - "revision": "0e706b53052c86b247bb8037d1a3fbf2e913045e", 15:23:53 INFO - "slavename": "talos-linux64-ix-018", 15:23:53 INFO - "repo_path": "mozilla-central" 15:23:53 INFO - } 15:23:53 INFO - Finding installer, test and symbols from parent task. 15:23:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JhpfO-MQRZShFVt03MyVUA'}, attempt #1 15:23:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JhpfO-MQRZShFVt03MyVUA'}, attempt #1 15:23:54 INFO - Task dependencies: LVYn6kl1Sru36vqr-ftIgg 15:23:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg'}, attempt #1 15:23:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.tar.bz2 15:23:54 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.test_packages.json 15:23:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.crashreporter-symbols.zip 15:23:54 INFO - Running post-action listener: _resource_record_post_action 15:23:54 INFO - [mozharness: 2017-09-13 22:23:54.842194Z] Finished read-buildbot-config step (success) 15:23:54 INFO - [mozharness: 2017-09-13 22:23:54.842411Z] Running download-and-extract step. 15:23:54 INFO - Running pre-action listener: _resource_record_pre_action 15:23:54 INFO - Running main action method: download_and_extract 15:23:54 INFO - mkdir: /builds/slave/test/build/tests 15:23:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:23:54 INFO - trying https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.test_packages.json 15:23:54 INFO - Downloading https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:23:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:23:55 INFO - Downloaded 989 bytes. 15:23:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:23:55 INFO - Using the following test package requirements: 15:23:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:23:55 INFO - u'common': [u'target.common.tests.zip'], 15:23:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:23:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:23:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:23:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:23:55 INFO - u'mozbase': [u'target.common.tests.zip'], 15:23:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:23:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:23:55 INFO - u'web-platform': [u'target.common.tests.zip', 15:23:55 INFO - u'target.web-platform.tests.tar.gz'], 15:23:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:23:55 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:23:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.common.tests.zip 15:23:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:23:55 INFO - Fetch https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.common.tests.zip into memory 15:23:57 INFO - Content-Length response header: 54570176 15:23:57 INFO - Bytes received: 54570176 15:24:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.talos.tests.zip 15:24:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:24:07 INFO - Fetch https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.talos.tests.zip into memory 15:24:08 INFO - Content-Length response header: 14499226 15:24:08 INFO - Bytes received: 14499226 15:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:08 INFO - trying https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.tar.bz2 15:24:08 INFO - Downloading https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:24:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:24:09 INFO - Downloaded 61235128 bytes. 15:24:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.tar.bz2 15:24:10 INFO - mkdir: /builds/slave/test/properties 15:24:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:24:10 INFO - Writing to file /builds/slave/test/properties/build_url 15:24:10 INFO - Contents: 15:24:10 INFO - build_url:https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.tar.bz2 15:24:10 INFO - Running post-action listener: _resource_record_post_action 15:24:10 INFO - Running post-action listener: find_tests_for_verification 15:24:10 INFO - Running post-action listener: set_extra_try_arguments 15:24:10 INFO - [mozharness: 2017-09-13 22:24:10.149992Z] Finished download-and-extract step (success) 15:24:10 INFO - [mozharness: 2017-09-13 22:24:10.150093Z] Running populate-webroot step. 15:24:10 INFO - Running pre-action listener: _resource_record_pre_action 15:24:10 INFO - Running main action method: populate_webroot 15:24:10 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:24:10 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['tresize', 15:24:10 INFO - 'tcanvasmark']}, 15:24:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:24:10 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['dromaeo_css', 15:24:10 INFO - 'kraken']}, 15:24:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:24:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:10 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:24:10 INFO - 'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:24:10 INFO - 'tests': ['damp', 'tps']}, 15:24:10 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:24:10 INFO - 'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['damp', 'tps']}, 15:24:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:24:10 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['dromaeo_dom']}, 15:24:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:24:10 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['basic_compositor_video', 15:24:10 INFO - 'glvideo']}, 15:24:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:24:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:24:10 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:24:10 INFO - 'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['ts_paint_webext', 15:24:10 INFO - 'tp5o_webext']}, 15:24:10 INFO - 'other-e10s': {'tests': ['a11yr', 15:24:10 INFO - 'ts_paint', 15:24:10 INFO - 'tpaint', 15:24:10 INFO - 'sessionrestore', 15:24:10 INFO - 'sessionrestore_many_windows', 15:24:10 INFO - 'sessionrestore_no_auto_restore', 15:24:10 INFO - 'tabpaint']}, 15:24:10 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['a11yr', 15:24:10 INFO - 'ts_paint', 15:24:10 INFO - 'tpaint', 15:24:10 INFO - 'sessionrestore', 15:24:10 INFO - 'sessionrestore_many_windows', 15:24:10 INFO - 'sessionrestore_no_auto_restore', 15:24:10 INFO - 'tabpaint']}, 15:24:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:24:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:24:10 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['bloom_basic_singleton']}, 15:24:10 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['bloom_basic']}, 15:24:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:24:10 INFO - 'tsvgr_opacity', 15:24:10 INFO - 'tart', 15:24:10 INFO - 'tscrollx', 15:24:10 INFO - 'tsvg_static']}, 15:24:10 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['tsvgx', 15:24:10 INFO - 'tsvgr_opacity', 15:24:10 INFO - 'tart', 15:24:10 INFO - 'tscrollx', 15:24:10 INFO - 'tsvg_static']}, 15:24:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:24:10 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:24:10 INFO - 'talos_options': ['--disable-stylo'], 15:24:10 INFO - 'tests': ['tp5o']}, 15:24:10 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:24:10 INFO - 'talos_options': ['--mitmproxy', 15:24:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:24:10 INFO - '--firstNonBlankPaint'], 15:24:10 INFO - 'tests': ['tp6_google', 15:24:10 INFO - 'tp6_youtube', 15:24:10 INFO - 'tp6_amazon', 15:24:10 INFO - 'tp6_facebook']}, 15:24:10 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:24:10 INFO - 'talos_options': ['--disable-stylo', 15:24:10 INFO - '--mitmproxy', 15:24:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:24:10 INFO - '--firstNonBlankPaint'], 15:24:10 INFO - 'tests': ['tp6_google', 15:24:10 INFO - 'tp6_youtube', 15:24:10 INFO - 'tp6_amazon', 15:24:10 INFO - 'tp6_facebook']}, 15:24:10 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:24:10 INFO - 'talos_options': ['--stylo-threads=1', 15:24:10 INFO - '--mitmproxy', 15:24:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:24:10 INFO - '--firstNonBlankPaint'], 15:24:10 INFO - 'tests': ['tp6_google', 15:24:10 INFO - 'tp6_youtube', 15:24:10 INFO - 'tp6_amazon', 15:24:10 INFO - 'tp6_facebook']}, 15:24:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:24:10 INFO - 'talos_options': ['--xperf_path', 15:24:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:24:10 INFO - 'tests': ['tp5n']}, 15:24:10 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:24:10 INFO - 'talos_options': ['--disable-stylo', 15:24:10 INFO - '--xperf_path', 15:24:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:24:10 INFO - 'tests': ['tp5n']}}} 15:24:10 INFO - Running post-action listener: _resource_record_post_action 15:24:10 INFO - [mozharness: 2017-09-13 22:24:10.166517Z] Finished populate-webroot step (success) 15:24:10 INFO - [mozharness: 2017-09-13 22:24:10.166730Z] Running create-virtualenv step. 15:24:10 INFO - Running pre-action listener: _resource_record_pre_action 15:24:10 INFO - Running main action method: create_virtualenv 15:24:10 INFO - Creating virtualenv /builds/slave/test/build/venv 15:24:10 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:24:10 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:24:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:24:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:24:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:24:10 INFO - Installing distribute.............................................................................................................................................................................................done. 15:24:11 INFO - Installing pip...............done. 15:24:11 INFO - Return code: 0 15:24:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:24:11 INFO - Reading from file tmpfile_stdout 15:24:11 INFO - Output received: 15:24:11 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:24:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:24:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:11 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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:24:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:24:11 INFO - Using env: {'DISPLAY': ':0', 15:24:11 INFO - 'HOME': '/home/cltbld', 15:24:11 INFO - 'LANG': 'en_US.UTF-8', 15:24:11 INFO - 'LANGUAGE': 'en_US:en', 15:24:11 INFO - 'LOGNAME': 'cltbld', 15:24:11 INFO - 'MAIL': '/var/mail/cltbld', 15:24:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:11 INFO - 'MOZ_NO_REMOTE': '1', 15:24:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:11 INFO - 'NO_EM_RESTART': '1', 15:24:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:11 INFO - 'PWD': '/builds/slave/test', 15:24:11 INFO - 'SHELL': '/bin/bash', 15:24:11 INFO - 'SHLVL': '1', 15:24:11 INFO - 'TERM': 'linux', 15:24:11 INFO - 'TMOUT': '86400', 15:24:11 INFO - 'USER': 'cltbld', 15:24:11 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:11 INFO - '_': '/tools/buildbot/bin/python'} 15:24:12 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:24:12 INFO - Downloading/unpacking pip>=1.5 15:24:12 INFO - Running setup.py egg_info for package pip 15:24:12 INFO - warning: no files found matching 'pip/cacert.pem' 15:24:12 INFO - warning: no files found matching '*.html' under directory 'docs' 15:24:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:24:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:24:12 INFO - Installing collected packages: pip 15:24:12 INFO - Found existing installation: pip 1.1 15:24:12 INFO - Uninstalling pip: 15:24:12 INFO - Successfully uninstalled pip 15:24:12 INFO - Running setup.py install for pip 15:24:12 INFO - warning: no files found matching 'pip/cacert.pem' 15:24:12 INFO - warning: no files found matching '*.html' under directory 'docs' 15:24:12 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:24:12 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:24:12 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Successfully installed pip 15:24:12 INFO - Cleaning up... 15:24:12 INFO - Return code: 0 15:24:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:24:12 INFO - Using env: {'DISPLAY': ':0', 15:24:12 INFO - 'HOME': '/home/cltbld', 15:24:12 INFO - 'LANG': 'en_US.UTF-8', 15:24:12 INFO - 'LANGUAGE': 'en_US:en', 15:24:12 INFO - 'LOGNAME': 'cltbld', 15:24:12 INFO - 'MAIL': '/var/mail/cltbld', 15:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:12 INFO - 'MOZ_NO_REMOTE': '1', 15:24:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:12 INFO - 'NO_EM_RESTART': '1', 15:24:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:12 INFO - 'PWD': '/builds/slave/test', 15:24:12 INFO - 'SHELL': '/bin/bash', 15:24:12 INFO - 'SHLVL': '1', 15:24:12 INFO - 'TERM': 'linux', 15:24:12 INFO - 'TMOUT': '86400', 15:24:12 INFO - 'USER': 'cltbld', 15:24:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:12 INFO - '_': '/tools/buildbot/bin/python'} 15:24:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:12 INFO - Downloading/unpacking psutil>=3.1.1 15:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:24:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:13 INFO - Installing collected packages: psutil 15:24:13 INFO - Running setup.py install for psutil 15:24:13 INFO - building 'psutil._psutil_linux' extension 15:24:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:24:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:24:14 INFO - building 'psutil._psutil_posix' extension 15:24:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:24:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:24:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:14 INFO - Successfully installed psutil 15:24:14 INFO - Cleaning up... 15:24:14 INFO - Return code: 0 15:24:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:14 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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:24:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:24:14 INFO - Using env: {'DISPLAY': ':0', 15:24:14 INFO - 'HOME': '/home/cltbld', 15:24:14 INFO - 'LANG': 'en_US.UTF-8', 15:24:14 INFO - 'LANGUAGE': 'en_US:en', 15:24:14 INFO - 'LOGNAME': 'cltbld', 15:24:14 INFO - 'MAIL': '/var/mail/cltbld', 15:24:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:14 INFO - 'MOZ_NO_REMOTE': '1', 15:24:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:14 INFO - 'NO_EM_RESTART': '1', 15:24:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:14 INFO - 'PWD': '/builds/slave/test', 15:24:14 INFO - 'SHELL': '/bin/bash', 15:24:14 INFO - 'SHLVL': '1', 15:24:14 INFO - 'TERM': 'linux', 15:24:14 INFO - 'TMOUT': '86400', 15:24:14 INFO - 'USER': 'cltbld', 15:24:14 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:14 INFO - '_': '/tools/buildbot/bin/python'} 15:24:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:24:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:24:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:24:15 INFO - Installing collected packages: mozsystemmonitor 15:24:15 INFO - Running setup.py install for mozsystemmonitor 15:24:15 INFO - Successfully installed mozsystemmonitor 15:24:15 INFO - Cleaning up... 15:24:16 INFO - Return code: 0 15:24:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:16 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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:24:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:24:16 INFO - Using env: {'DISPLAY': ':0', 15:24:16 INFO - 'HOME': '/home/cltbld', 15:24:16 INFO - 'LANG': 'en_US.UTF-8', 15:24:16 INFO - 'LANGUAGE': 'en_US:en', 15:24:16 INFO - 'LOGNAME': 'cltbld', 15:24:16 INFO - 'MAIL': '/var/mail/cltbld', 15:24:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:16 INFO - 'MOZ_NO_REMOTE': '1', 15:24:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:16 INFO - 'NO_EM_RESTART': '1', 15:24:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:16 INFO - 'PWD': '/builds/slave/test', 15:24:16 INFO - 'SHELL': '/bin/bash', 15:24:16 INFO - 'SHLVL': '1', 15:24:16 INFO - 'TERM': 'linux', 15:24:16 INFO - 'TMOUT': '86400', 15:24:16 INFO - 'USER': 'cltbld', 15:24:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:16 INFO - '_': '/tools/buildbot/bin/python'} 15:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:17 INFO - Downloading/unpacking jsonschema==2.5.1 15:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:24:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:18 INFO - Downloading functools32-3.2.3-2.tar.gz 15:24:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:24:18 INFO - warning: no files found matching '*.txt' 15:24:18 INFO - no previously-included directories found matching 'build' 15:24:18 INFO - no previously-included directories found matching 'dist' 15:24:18 INFO - no previously-included directories found matching '.git*' 15:24:18 INFO - Installing collected packages: jsonschema, functools32 15:24:20 INFO - Running setup.py install for functools32 15:24:20 INFO - warning: no files found matching '*.txt' 15:24:20 INFO - no previously-included directories found matching 'build' 15:24:20 INFO - no previously-included directories found matching 'dist' 15:24:20 INFO - no previously-included directories found matching '.git*' 15:24:20 INFO - Successfully installed jsonschema functools32 15:24:20 INFO - Cleaning up... 15:24:20 INFO - Return code: 0 15:24:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:20 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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:24:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:24:20 INFO - Using env: {'DISPLAY': ':0', 15:24:20 INFO - 'HOME': '/home/cltbld', 15:24:20 INFO - 'LANG': 'en_US.UTF-8', 15:24:20 INFO - 'LANGUAGE': 'en_US:en', 15:24:20 INFO - 'LOGNAME': 'cltbld', 15:24:20 INFO - 'MAIL': '/var/mail/cltbld', 15:24:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:20 INFO - 'MOZ_NO_REMOTE': '1', 15:24:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:20 INFO - 'NO_EM_RESTART': '1', 15:24:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:20 INFO - 'PWD': '/builds/slave/test', 15:24:20 INFO - 'SHELL': '/bin/bash', 15:24:20 INFO - 'SHLVL': '1', 15:24:20 INFO - 'TERM': 'linux', 15:24:20 INFO - 'TMOUT': '86400', 15:24:20 INFO - 'USER': 'cltbld', 15:24:20 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:20 INFO - '_': '/tools/buildbot/bin/python'} 15:24:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:24:21 INFO - Cleaning up... 15:24:21 INFO - Return code: 0 15:24:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:24:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:24:21 INFO - Using env: {'DISPLAY': ':0', 15:24:21 INFO - 'HOME': '/home/cltbld', 15:24:21 INFO - 'LANG': 'en_US.UTF-8', 15:24:21 INFO - 'LANGUAGE': 'en_US:en', 15:24:21 INFO - 'LOGNAME': 'cltbld', 15:24:21 INFO - 'MAIL': '/var/mail/cltbld', 15:24:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:21 INFO - 'MOZ_NO_REMOTE': '1', 15:24:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:21 INFO - 'NO_EM_RESTART': '1', 15:24:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:21 INFO - 'PWD': '/builds/slave/test', 15:24:21 INFO - 'SHELL': '/bin/bash', 15:24:21 INFO - 'SHLVL': '1', 15:24:21 INFO - 'TERM': 'linux', 15:24:21 INFO - 'TMOUT': '86400', 15:24:21 INFO - 'USER': 'cltbld', 15:24:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:21 INFO - '_': '/tools/buildbot/bin/python'} 15:24:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:21 INFO - Downloading/unpacking blobuploader==1.2.4 15:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:22 INFO - Downloading blobuploader-1.2.4.tar.gz 15:24:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:24:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:24:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:23 INFO - Downloading docopt-0.6.1.tar.gz 15:24:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:24:23 INFO - Installing collected packages: blobuploader, requests, docopt 15:24:23 INFO - Running setup.py install for blobuploader 15:24:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:24:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:24:23 INFO - Running setup.py install for requests 15:24:23 INFO - Running setup.py install for docopt 15:24:23 INFO - Successfully installed blobuploader requests docopt 15:24:23 INFO - Cleaning up... 15:24:23 INFO - Return code: 0 15:24:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:23 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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:24:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:24:23 INFO - Using env: {'DISPLAY': ':0', 15:24:23 INFO - 'HOME': '/home/cltbld', 15:24:23 INFO - 'LANG': 'en_US.UTF-8', 15:24:23 INFO - 'LANGUAGE': 'en_US:en', 15:24:23 INFO - 'LOGNAME': 'cltbld', 15:24:23 INFO - 'MAIL': '/var/mail/cltbld', 15:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:23 INFO - 'MOZ_NO_REMOTE': '1', 15:24:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:23 INFO - 'NO_EM_RESTART': '1', 15:24:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:23 INFO - 'PWD': '/builds/slave/test', 15:24:23 INFO - 'SHELL': '/bin/bash', 15:24:23 INFO - 'SHLVL': '1', 15:24:23 INFO - 'TERM': 'linux', 15:24:23 INFO - 'TMOUT': '86400', 15:24:23 INFO - 'USER': 'cltbld', 15:24:23 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:23 INFO - '_': '/tools/buildbot/bin/python'} 15:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:23 INFO - Running setup.py (path:/tmp/pip-8s52GF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:24 INFO - Running setup.py (path:/tmp/pip-SnZUUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:24 INFO - Running setup.py (path:/tmp/pip-x_Laqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:24 INFO - Running setup.py (path:/tmp/pip-Tc7E5X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:24 INFO - Running setup.py (path:/tmp/pip-_DOi3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:24 INFO - Running setup.py (path:/tmp/pip-6gkRGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:24 INFO - Running setup.py (path:/tmp/pip-O14fXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:24 INFO - Running setup.py (path:/tmp/pip-GaEP69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:24 INFO - Running setup.py (path:/tmp/pip-d5o5sB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:24 INFO - Running setup.py (path:/tmp/pip-QhgAm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:24 INFO - Running setup.py (path:/tmp/pip-PJlYIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:24 INFO - Running setup.py (path:/tmp/pip-dMClup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:24 INFO - Running setup.py (path:/tmp/pip-GoHtzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:24 INFO - Running setup.py (path:/tmp/pip-4JrtX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:24 INFO - Running setup.py (path:/tmp/pip-4qXkB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:25 INFO - Running setup.py (path:/tmp/pip-BwxfR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:25 INFO - Running setup.py (path:/tmp/pip-uVc2hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:24:25 INFO - Running setup.py install for manifestparser 15:24:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:24:25 INFO - Running setup.py install for mozcrash 15:24:25 INFO - Running setup.py install for mozdebug 15:24:25 INFO - Running setup.py install for mozdevice 15:24:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:24:25 INFO - Running setup.py install for mozfile 15:24:25 INFO - Running setup.py install for mozhttpd 15:24:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:24:25 INFO - Running setup.py install for mozinfo 15:24:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:24:25 INFO - Running setup.py install for mozInstall 15:24:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:24:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:24:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:24:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:24:25 INFO - Running setup.py install for mozleak 15:24:26 INFO - Running setup.py install for mozlog 15:24:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:24:26 INFO - Running setup.py install for moznetwork 15:24:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:24:26 INFO - Running setup.py install for mozprocess 15:24:26 INFO - Running setup.py install for mozprofile 15:24:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:24:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:24:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:24:26 INFO - Running setup.py install for mozrunner 15:24:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:24:26 INFO - Running setup.py install for mozscreenshot 15:24:26 INFO - Running setup.py install for moztest 15:24:26 INFO - Running setup.py install for mozversion 15:24:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:24:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:24:26 INFO - Cleaning up... 15:24:26 INFO - Return code: 0 15:24:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:26 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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:24:26 INFO - Using env: {'DISPLAY': ':0', 15:24:26 INFO - 'HOME': '/home/cltbld', 15:24:26 INFO - 'LANG': 'en_US.UTF-8', 15:24:26 INFO - 'LANGUAGE': 'en_US:en', 15:24:26 INFO - 'LOGNAME': 'cltbld', 15:24:26 INFO - 'MAIL': '/var/mail/cltbld', 15:24:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:26 INFO - 'MOZ_NO_REMOTE': '1', 15:24:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:26 INFO - 'NO_EM_RESTART': '1', 15:24:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:26 INFO - 'PWD': '/builds/slave/test', 15:24:26 INFO - 'SHELL': '/bin/bash', 15:24:26 INFO - 'SHLVL': '1', 15:24:26 INFO - 'TERM': 'linux', 15:24:26 INFO - 'TMOUT': '86400', 15:24:26 INFO - 'USER': 'cltbld', 15:24:26 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:26 INFO - '_': '/tools/buildbot/bin/python'} 15:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:27 INFO - Running setup.py (path:/tmp/pip-bV6sgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:27 INFO - Running setup.py (path:/tmp/pip-OXeno3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:27 INFO - Running setup.py (path:/tmp/pip-oY1FbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:27 INFO - Running setup.py (path:/tmp/pip-m0UHxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:27 INFO - Running setup.py (path:/tmp/pip-_moY7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:27 INFO - Running setup.py (path:/tmp/pip-5Yy4ZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:27 INFO - Running setup.py (path:/tmp/pip-4kBwZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:27 INFO - Running setup.py (path:/tmp/pip-BSGvxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:27 INFO - Running setup.py (path:/tmp/pip-LwRSGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:27 INFO - Running setup.py (path:/tmp/pip-YvNscS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:27 INFO - Running setup.py (path:/tmp/pip-1vcNkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:27 INFO - Running setup.py (path:/tmp/pip-th5pqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:28 INFO - Running setup.py (path:/tmp/pip-v2tymc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:28 INFO - Running setup.py (path:/tmp/pip-XZzuIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:28 INFO - Running setup.py (path:/tmp/pip-rUYJNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:28 INFO - Running setup.py (path:/tmp/pip-xVtzeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:28 INFO - Running setup.py (path:/tmp/pip-8AhWzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:24:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:29 INFO - Downloading blessings-1.6.tar.gz 15:24:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:24:29 INFO - Installing collected packages: blessings 15:24:29 INFO - Running setup.py install for blessings 15:24:29 INFO - Successfully installed blessings 15:24:29 INFO - Cleaning up... 15:24:29 INFO - Return code: 0 15:24:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:24:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:29 INFO - Reading from file tmpfile_stdout 15:24:29 INFO - Current package versions: 15:24:29 INFO - argparse == 1.2.1 15:24:29 INFO - blessings == 1.6 15:24:29 INFO - blobuploader == 1.2.4 15:24:29 INFO - docopt == 0.6.1 15:24:29 INFO - functools32 == 3.2.3-2 15:24:29 INFO - jsonschema == 2.5.1 15:24:29 INFO - manifestparser == 1.1 15:24:29 INFO - mozInstall == 1.13 15:24:29 INFO - mozcrash == 1.0 15:24:29 INFO - mozdebug == 0.1 15:24:29 INFO - mozdevice == 0.51 15:24:29 INFO - mozfile == 1.2 15:24:29 INFO - mozhttpd == 0.7 15:24:29 INFO - mozinfo == 0.10 15:24:29 INFO - mozleak == 0.1 15:24:29 INFO - mozlog == 3.5 15:24:29 INFO - moznetwork == 0.27 15:24:29 INFO - mozprocess == 0.25 15:24:29 INFO - mozprofile == 0.28 15:24:29 INFO - mozrunner == 6.13 15:24:29 INFO - mozscreenshot == 0.1 15:24:29 INFO - mozsystemmonitor == 0.3 15:24:29 INFO - moztest == 0.8 15:24:29 INFO - mozversion == 1.4 15:24:29 INFO - psutil == 3.1.1 15:24:29 INFO - requests == 1.2.3 15:24:29 INFO - wsgiref == 0.1.2 15:24:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:29 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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:24:29 INFO - Using env: {'DISPLAY': ':0', 15:24:29 INFO - 'HOME': '/home/cltbld', 15:24:29 INFO - 'LANG': 'en_US.UTF-8', 15:24:29 INFO - 'LANGUAGE': 'en_US:en', 15:24:29 INFO - 'LOGNAME': 'cltbld', 15:24:29 INFO - 'MAIL': '/var/mail/cltbld', 15:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:29 INFO - 'MOZ_NO_REMOTE': '1', 15:24:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:29 INFO - 'NO_EM_RESTART': '1', 15:24:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:29 INFO - 'PWD': '/builds/slave/test', 15:24:29 INFO - 'SHELL': '/bin/bash', 15:24:29 INFO - 'SHLVL': '1', 15:24:29 INFO - 'TERM': 'linux', 15:24:29 INFO - 'TMOUT': '86400', 15:24:29 INFO - 'USER': 'cltbld', 15:24:29 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:29 INFO - '_': '/tools/buildbot/bin/python'} 15:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:24:30 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:24:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:24:31 INFO - Installing collected packages: simplejson 15:24:31 INFO - Running setup.py install for simplejson 15:24:31 INFO - building 'simplejson._speedups' extension 15:24:31 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:24:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:24:32 INFO - Successfully installed simplejson 15:24:32 INFO - Cleaning up... 15:24:32 INFO - Return code: 0 15:24:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:32 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 0xd4c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf15a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b8a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, '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': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:24:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:24:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:24:32 INFO - Using env: {'DISPLAY': ':0', 15:24:32 INFO - 'HOME': '/home/cltbld', 15:24:32 INFO - 'LANG': 'en_US.UTF-8', 15:24:32 INFO - 'LANGUAGE': 'en_US:en', 15:24:32 INFO - 'LOGNAME': 'cltbld', 15:24:32 INFO - 'MAIL': '/var/mail/cltbld', 15:24:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:32 INFO - 'MOZ_NO_REMOTE': '1', 15:24:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:32 INFO - 'NO_EM_RESTART': '1', 15:24:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:32 INFO - 'PWD': '/builds/slave/test', 15:24:32 INFO - 'SHELL': '/bin/bash', 15:24:32 INFO - 'SHLVL': '1', 15:24:32 INFO - 'TERM': 'linux', 15:24:32 INFO - 'TMOUT': '86400', 15:24:32 INFO - 'USER': 'cltbld', 15:24:32 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:32 INFO - '_': '/tools/buildbot/bin/python'} 15:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:24:32 INFO - Cleaning up... 15:24:32 INFO - Return code: 0 15:24:32 INFO - Running post-action listener: _resource_record_post_action 15:24:32 INFO - Running post-action listener: _start_resource_monitoring 15:24:32 INFO - Starting resource monitoring. 15:24:32 INFO - [mozharness: 2017-09-13 22:24:32.615974Z] Finished create-virtualenv step (success) 15:24:32 INFO - [mozharness: 2017-09-13 22:24:32.616480Z] Running install step. 15:24:32 INFO - Running pre-action listener: _resource_record_pre_action 15:24:32 INFO - Running main action method: install 15:24:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:32 INFO - Reading from file tmpfile_stdout 15:24:32 INFO - Detecting whether we're running mozinstall >=1.0... 15:24:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:24:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:24:32 INFO - Reading from file tmpfile_stdout 15:24:32 INFO - Output received: 15:24:32 INFO - Usage: mozinstall [options] installer 15:24:32 INFO - Options: 15:24:32 INFO - -h, --help show this help message and exit 15:24:32 INFO - -d DEST, --destination=DEST 15:24:32 INFO - Directory to install application into. [default: 15:24:32 INFO - "/builds/slave/test"] 15:24:32 INFO - --app=APP Application being installed. [default: firefox] 15:24:32 INFO - mkdir: /builds/slave/test/build/application 15:24:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:24:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:24:50 INFO - Reading from file tmpfile_stdout 15:24:50 INFO - Output received: 15:24:50 INFO - /builds/slave/test/build/application/firefox/firefox 15:24:50 INFO - Running post-action listener: _resource_record_post_action 15:24:50 INFO - [mozharness: 2017-09-13 22:24:50.691040Z] Finished install step (success) 15:24:50 INFO - [mozharness: 2017-09-13 22:24:50.691291Z] Running setup-mitmproxy step. 15:24:50 INFO - Running pre-action listener: _resource_record_pre_action 15:24:50 INFO - Running main action method: setup_mitmproxy 15:24:50 INFO - Skipping: mitmproxy is not required 15:24:50 INFO - Running post-action listener: _resource_record_post_action 15:24:50 INFO - [mozharness: 2017-09-13 22:24:50.692422Z] Finished setup-mitmproxy step (success) 15:24:50 INFO - [mozharness: 2017-09-13 22:24:50.692643Z] Running run-tests step. 15:24:50 INFO - Running pre-action listener: _resource_record_pre_action 15:24:50 INFO - Running pre-action listener: _set_gcov_prefix 15:24:50 INFO - Running main action method: run_tests 15:24:50 WARNING - Try message not found. 15:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:24:50 INFO - Python 2.7.3 15:24:50 INFO - Return code: 0 15:24:50 INFO - grabbing minidump binary from tooltool 15:24: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')]} 15:24:50 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 0x10bc090>, '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 0x10bcd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b6f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:24:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:24:50 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:24:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:24:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:24:50 INFO - Return code: 0 15:24:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:24:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:24:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:24:50 INFO - ENV: RUST_BACKTRACE is now full 15:24:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:24:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:24:50 INFO - Using env: {'DISPLAY': ':0', 15:24:50 INFO - 'HOME': '/home/cltbld', 15:24:50 INFO - 'LANG': 'en_US.UTF-8', 15:24:50 INFO - 'LANGUAGE': 'en_US:en', 15:24:50 INFO - 'LOGNAME': 'cltbld', 15:24:50 INFO - 'MAIL': '/var/mail/cltbld', 15:24:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:24:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:50 INFO - 'MOZ_NO_REMOTE': '1', 15:24:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:50 INFO - 'NO_EM_RESTART': '1', 15:24:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:50 INFO - 'PWD': '/builds/slave/test', 15:24:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:24:50 INFO - 'RUST_BACKTRACE': 'full', 15:24:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:24:50 INFO - 'SHELL': '/bin/bash', 15:24:50 INFO - 'SHLVL': '1', 15:24:50 INFO - 'TERM': 'linux', 15:24:50 INFO - 'TMOUT': '86400', 15:24:50 INFO - 'USER': 'cltbld', 15:24:50 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:50 INFO - '_': '/tools/buildbot/bin/python'} 15:24:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:24:51 INFO - mozversion application_buildid: 20170913213200 15:24:51 INFO - mozversion application_changeset: 0e706b53052c86b247bb8037d1a3fbf2e913045e 15:24:51 INFO - mozversion application_display_name: Nightly 15:24:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:24:51 INFO - mozversion application_name: Firefox 15:24:51 INFO - mozversion application_remotingname: firefox 15:24:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:24:51 INFO - mozversion application_vendor: Mozilla 15:24:51 INFO - mozversion application_version: 57.0a1 15:24:51 INFO - mozversion platform_buildid: 20170913213200 15:24:51 INFO - mozversion platform_changeset: 0e706b53052c86b247bb8037d1a3fbf2e913045e 15:24:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:24:51 INFO - mozversion platform_version: 57.0a1 15:24:51 INFO - using testdate: 1505341491 15:24:51 INFO - actual date: 1505341491 15:24:51 INFO - starting webserver on 'localhost:45856' 15:24:51 INFO - SUITE-START | Running 2 tests 15:24:51 INFO - TEST-START | dromaeo_css 15:24:51 INFO - Initialising browser for dromaeo_css test... 15:24:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45856/getInfo.html -profile /tmp/tmpnj5AGO/profile 15:24:51 INFO - TEST-INFO | started process 1742 (/builds/slave/test/build/application/firefox/firefox http://localhost:45856/getInfo.html) 15:24:57 INFO - TEST-INFO | 1742: exit 0 15:24:57 INFO - Browser initialized. 15:24:57 INFO - Running cycle 1/1 for dromaeo_css test... 15:24:57 INFO - Using env: {'DISPLAY': ':0', 15:24:57 INFO - 'HOME': '/home/cltbld', 15:24:57 INFO - 'JSGC_DISABLE_POISONING': '1', 15:24:57 INFO - 'LANG': 'en_US.UTF-8', 15:24:57 INFO - 'LANGUAGE': 'en_US:en', 15:24:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:24:57 INFO - 'LOGNAME': 'cltbld', 15:24:57 INFO - 'MAIL': '/var/mail/cltbld', 15:24:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:24:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:24:57 INFO - 'MOZ_CRASHREPORTER': '1', 15:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:24:57 INFO - 'MOZ_NO_REMOTE': '1', 15:24:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:24:57 INFO - 'NO_EM_RESTART': '1', 15:24:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:57 INFO - 'PWD': '/builds/slave/test', 15:24:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:24:57 INFO - 'RUST_BACKTRACE': 'full', 15:24:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:24:57 INFO - 'SHELL': '/bin/bash', 15:24:57 INFO - 'SHLVL': '1', 15:24:57 INFO - 'TERM': 'linux', 15:24:57 INFO - 'TMOUT': '86400', 15:24:57 INFO - 'USER': 'cltbld', 15:24:57 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:57 INFO - '_': '/tools/buildbot/bin/python'} 15:24:57 INFO - TEST-INFO | started process 1977 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnj5AGO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:24:58 INFO - PID 1977 | 15:24:58 INFO - PID 1977 | (/builds/slave/test/build/application/firefox/firefox:2026): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:24:58 INFO - PID 1977 | 15:25:09 INFO - PID 1977 | RSS: Main: 174428160 15:25:09 INFO - PID 1977 | 15:28:06 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:45856/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45856/tests/dromaeo/cssquery-ext.html) 15:28:07 INFO - PID 1977 | RSS: Main: 176996352 15:28:07 INFO - PID 1977 | 15:31:09 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:45856/tests/dromaeo/cssquery-ext.html (next: http://localhost:45856/tests/dromaeo/cssquery-jquery.html) 15:31:09 INFO - PID 1977 | RSS: Main: 172965888 15:31:09 INFO - PID 1977 | 15:34:11 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:45856/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45856/tests/dromaeo/cssquery-mootools.html) 15:34:12 INFO - PID 1977 | RSS: Main: 175726592 15:34:12 INFO - PID 1977 | 15:37:13 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:45856/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45856/tests/dromaeo/cssquery-prototype.html) 15:37:14 INFO - PID 1977 | RSS: Main: 174108672 15:37:14 INFO - PID 1977 | 15:40:17 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:45856/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45856/tests/dromaeo/cssquery-yui.html) 15:40:18 INFO - PID 1977 | RSS: Main: 175874048 15:40:18 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | Cycle 1(1): loaded http://localhost:45856/tests/dromaeo/cssquery-yui.html (next: http://localhost:45856/tests/dromaeo/cssquery-dojo.html) 15:43:20 INFO - PID 1977 | RSS: Main: 179163136 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | __start_tp_report 15:43:20 INFO - PID 1977 | _x_x_mozilla_page_load 15:43:20 INFO - PID 1977 | _x_x_mozilla_page_load_details 15:43:20 INFO - PID 1977 | |i|pagename|runs| 15:43:20 INFO - PID 1977 | |0;dojo.html;3090.3201295714944;3138.152698771332;3196.9929472216236;3197.232664160599;3204.47126224173;6225.501959843213;6267.968660156693;6318.494520438366;6457.8708425831455;6468.450181734553;6185.907211391822;6268.592541484801;6321.968390158055;6352.904706429395;6383.648899310536;1330.9005044542234;5813.040848260032;5858;5877.412258774109;5945.732442040118;5680.9715951420185;5815.040518314473;5815.62198457101;5834.854128646775;5879;5782;5807.070868661036;5874.383189765099;5875.559333050017;5889.146073819272;5797.159411885295;5817.56368272377;5848.707564621752;5866.853328666774;5889.381614930457;5820.505257053155;5822.359540450547;5871.530277577784;5880.764769409218;5888.676122813244;6161.921663708833;6284.183056202664;6335.3981371769605;6427.87144257117;6463.612183268972;6621.602703837785;6697.765578204788;6714;6721.227058888241;6810.62541560214;6875.449964002868;6908.1364829396325;6932.896006559906;6940.826479338007;6966.860662786767;6209.410106039919;6286.9056964145575;6355.046742988542;6373.776917807901;6409.647469389181;7130.251323610946;7161.248068952685;7163.3552980232025;7167.104111986002;7167.641617919083;6713.664316784141;6721.596704197764;6724.058631791553;6814.522983391115;6819;6641.966790166019;6770.627615481098;6784.118064651662;6901.240863505011;6925.342092501204;6813.182418109858;6842.4183944364295;6847.109875716125;6847.794566163023;6852.6231057292325;6773.424258938046;6883.621400823003;6947.826304342432;6950.478714096463;6951.2353641099435;5280.762365693553;5288.365396152486;5356.143017117242;5362.544183744348;5363.490468405495;4499.88750281239;4635.675502714777;4643.071385722869;4675.976620116946;4704.2473204287835;5759.913601295984;5770.971145144332;5806.071028635398;5869.647821130746;5874.353821079678;6905.965470172617;6941.618210998444;6981.127359080115;6995.755148569878;7010.649467526603;5845.298564172326;5853.970730146322;5930.229070220844;5945.048792193228;5946.197263369476;5810.360860305363;5855.063189889597;5856.385079566669;5883.558733094949;5954.49386802118;6649.268580456146;6666.83332916681;6671.299513551105;6673.899891501631;6709.127813384229;6842.692078856563;6843.589384636938;6850.177978642595;6929.826754331081;6934.965325173342;6610.504212184009;6640.236372816995;6701.229358623821;6713.731450741916;6717.899231511434;6535.869282614226;6624.635645039376;6743.055972163999;6760.357766012221;6812.795616131524;6739.393454589111;6971.477139214478;6981.301869812978;6981.650917454209;6982.546134501445;6477.838054048687;6517.152770139852;6746.190457145174;6760.628165450948;6767.255601883888;6641.535092543669;6644.4020038196795;6722.932770672231;6744;6772.119624448987;6416.358364163733;6479.416852483299;6487.675616219264;6555.475561954937;6657.933420665731;6592.307807680125;6649.335066493312;6664.766733164316;6699.631520266432;6860.519763616699;6500.674966251763;6549.8690026200165;6569.9014514783275;6577;6597.60414375139 15:43:20 INFO - PID 1977 | |1;ext.html;820.7455688736492;842.8356470488253;844.0209357145708;844.0420123160211;845.3701992015946;30726.38547229054;33592.49611255833;33639.49540756887;33672.831635841845;33830.32339353218;38823.805880970554;39162;39286.8035659822;39338.40992385116;39343.80328098363;6943.930560694392;7041.894371584431;7044.894326585079;7682.769516914474;7861.685532578718;7711.11322197946;7813.921860781376;7823.804404889866;7824;7834.72578459754;7776.65005074773;7793.337566306871;7814.413918956073;7819.139894611588;7840.607969601526;7742.961285193566;7779.611019449033;7793.376529877596;7808.687652493893;7836.412269079842;7721;7776.844463110706;7784.883226751604;7787.29914307715;7788.571628560427;37042.62957370446;37230.51077956879;37329.440058399145;37391.62608373936;37473.25053498942;3503.964960350415;3542.9114272143147;3545.556805399325;3549.2546565221332;3550.751447398683;31047.84476077628;31131.221719457193;31165.844170779;31174.688253117405;31288.21779455509;5061.468545802712;5077.466865979056;5101.183810590288;5102.540771330561;5136.4606716295;182799;182972;183285;184105;184800.99999999866;43131.56868431338;47579.76210118927;47680;47855.28217076747;47899.281510777364;45861.77069114633;51576.48423515792;51985;52105;52125;13937.303134843218;14008.85991140096;14039.017268791291;14052.789208161887;14075.366608502642;11205.271657342246;11249.66251012471;11257;11262.493187806405;11298.830517542243;14367.928160359132;14389.28053597316;14435.206063666388;14498.637534061732;14545.709085818224;5680.528969898025;5712.914306285409;5760.794619927393;5768.625039372428;5908.586398952032;982.6266018912768;999.2954966748425;1001.0340021878922;1001.8096561653408;1007.1590222164428;23080.65379019316;23086.65370019451;23144;23154.42113947131;23202.535949281257;1078.4338222433128;1082.1396989393452;1090.2477290669412;1098.6923661374828;1114.1198453222025;12056.517739290506;12084.214526055779;12121.21212121227;12161.33112678811;12299.323537205539;223932;225321;225601;225604;226204;52666;52888.471115289125;52955.205671914184;52983.20525192125;53072.46927530752;1418.5744276716944;1449.5506393018197;1458.183417286323;1461.167134733213;1462.7659574468034;22558.323250302517;22690.773092268864;22718.545629086995;22942.197023104112;23291.76708232964;37118.44322335221;37157;37195;37219.8139009314;37252;196787;196992;197527;197595;197668;33390.666093338754;35243.29513409666;36967.630323696416;37038;37220.44169337516;45520.77239613781;46657;46673.766631166625;46693.29960050534;46776.29835552401;33891;34002.489962651074;34361.969140925816;34531.30937381289;35062.82468587641;33131.668683312855;33916.83041584776;33964.150896227795;33983.49024764581;34332.48501272533;38260.234795304495;38554.22891542209;38560.61439385683;38658.806705967414;38952.610473896035;33275.500867486524;33412;33432.665673342955;33626.159346016546;34122.48816267708;24611.12273673934;24624;24713.629295560222;24906;24927.252182434557 15:43:20 INFO - PID 1977 | |2;jquery.html;560.4171661472067;575.1959198905337;580.8809194115207;592.7125344208059;605.2404232687976;22273.220437284697;22913.656295155564;22988.540229195405;23012.539749205007;23026;21306.57386852266;21409.14363425465;21456.678149827763;23292.951817168225;24564.63153052706;6194.101855230989;6409.519286053542;6415.518836087312;6417.422431981121;6436.967815160941;6221.284552276478;6292.2134733158355;6329;6334.461570766491;6335.651539165344;6576.736930522798;6581.111549940744;6581.967090164567;6585.934140658604;6586.176727909012;6224.408681175327;6563.901541476882;6565.671716414208;6576.868462630745;6578.177727784027;6007.158997740276;6033.3664965178905;6037.184980027684;6037.72830222641;6038.456538911475;28371.432571348665;28853.85573072142;28864.422711545652;28881;28939.42121157586;2050.5283784729445;2069.7412823397076;2077.1483691686485;2078.251829341436;2079.1683326669304;17742.20160092786;18139.637207255913;18383.356582519547;18545.721814172797;18643;2338.824588155895;2346.6831977682964;2382.2257766226044;2395.1856368834506;2398.784109430142;119973.40013299965;125089.37455312756;128882;129476.35261823724;133787;14278.2146981917;14452.205128717811;14604.926975365055;14630.26848657563;14634.268286585628;22599.322020339416;22706;22707.88646056759;22719.204827831276;22766;9606.855897161548;9872.753181170528;9882.256516871797;9933.25500587459;9981.151602113901;9027.187553120251;9066.637334506679;9079.500627465553;9175.77060573491;9185.173334399937;9008.549572521348;9014.59434325457;9065.59204835784;9070.909290907139;9132.269418446642;2428.3564855313357;2445.9755402446103;2453.1659235859893;2516.5721827289403;2637.6571045763926;930.3673502018557;939.5208443693765;944.419182202954;953.2945620240972;955.9283053770995;17907.1941762621;18532.629347413247;18642.533936651693;18819.52951176204;18896.338628148224;985.5417230987625;995.2187532786728;999.1706883286854;1003.19744204636;1005.969820905374;10149.695509134737;10265.589376425009;10308.896911030944;10391.948040259751;10623.468826558796;477957;483087;483879;484445;488388;108280;108753.45623271991;110150.44924775325;110488;110606;1114.804909140897;1122.2873475343054;1141.49774099396;1143.3311512765126;1148.0700632487712;16673.082980436193;16829.495115146565;16881.07154106536;16912.408065717642;17011.48965531036;54113.458865410845;54271.72864135654;55491;55648.16527752168;56110.71944640251;68419.31580684129;68539;69383.65308173427;69445.30554694388;69751;13829.239391833546;13907.37416816266;13948.511802086878;13981.370838312503;14014.299285035504;21724.565508690055;21827.345179644635;22019.22932697348;22041.779582204614;22056.779432206113;17386.391476298268;17668.64662706713;17774.91112544429;17787.377441789475;17793.55516112056;15914.761278581062;16083.126947351826;16326.673466530365;16426.753598695792;16669.41657042046;17801.287948481917;17973.281068757104;17977.640447190723;18185.272589096287;18250.269989200282;13629.931850340685;13735.107218031195;13757.17456952586;13758.724825503234;13813.792793108312;17473.301067957647;17682.469525914243;17716.557086072953;17809;17883 15:43:20 INFO - PID 1977 | |3;mootools.html;1290.341925617935;1358.1715153756206;1362.1486570893192;1379.08290986494;1382.8548002459747;15358.462453814105;15841.445549405758;15865.444709435173;15875.444359447421;15882.761758573602;15459;15558.766618500731;15581.454649087284;15595.220238988062;15602.687946241096;2707.10665480392;2731.877065532051;2743.890244390222;2764.626775385317;2769.4461107778425;2512.836665616738;2607.074488556554;2610.2560770180503;2610.843349399042;2638.261286839678;2422.806175505964;2563.5898256278997;2565.6921169459692;2568.5890257558794;2569.2035469004577;2696.312440327714;2697.069511018715;2724.741149590786;2739.328864428218;2741.4928238275884;2650.615660729203;2655.8406495610325;2729.372244383783;2761.254461295439;2780.471710375022;14535.854641453556;14755.336009879582;14755.409783608642;14756.335964881606;14771.113733176044;7957.681692732283;8216.83566328676;8228.753137405887;8230.135835737221;8240.62917168723;4749.715017098985;4953.108440480712;5011.423686276088;5018.247262910592;5044.369453818273;1870.3360307090923;1891.4042076745918;1893.5644801695687;1954.9120289587004;1961.460598335455;25277.620835687478;25744.742552574422;25955.870220648965;26443.73556264432;26500.204993850217;6727.293634168342;6754.155730533684;6769.3907548320885;6942.895856562156;6970.233274339818;16267.593310167103;16852.57868553296;17425.12874356277;17435.912820435817;17453.7381939271;7883.6846526138315;8061;8062.798430039296;8093.959530202312;8098.31164351025;7312;7410.703571857173;7419.85160296791;7424.665890034961;7455.888161677579;5165.48345165488;5295.788168473295;5316.3354580677415;5324.440933701983;5345.224942383333;1545.127003243164;1550.2481296571154;1552.5808031831343;1572.9134897580514;1579.8025246844145;882.0870399136991;901.1889299630385;904.3985749476525;908.9727308180766;910.1853840812436;7715.652795624211;7783.29950304464;7826.882596761053;7828.686852525835;7871.645775940096;696.7352406085756;698.0889938630137;714.2999660807245;716.4197000429668;718.3463048625725;4581.075344005208;4613.953860461353;4880.41435027799;4918.5573298403315;5007.1487847065355;19844.503887402643;21062.894685526473;21654.458638534164;21658.133674653152;21870.562588748136;20129.79870201279;20264.89867550682;21084.367468975954;21210.681839772416;21263.255786047415;11330.490127944262;11465.452163957925;11500;11504.079673625922;11565.421728913521;8229.876551851607;8247.752567422987;8256.711015114442;8257.21556452136;8280.71017514384;17980.190891409657;18010.099495024933;18234.817651823312;18536.629267414308;18655.16051777646;18555.628887421906;18633.161507731915;18660.253589856253;18748.250069997048;18845.057747112314;10454.268201226145;10652.414117223318;10653;10653.360798352123;10670.35977841332;13671.248081355621;13766.24285664298;13867.514636987657;13928.094673846168;14014.089084209494;11325.037371823488;11338;11474.254173478697;11619.128565357463;11640.94179529097;10840.728981775519;10864.619738309122;11199.496022678835;11222.326660400402;11292.153088518558;9770.755731106496;9779.60881564758;9795.951020244853;9856.730092668671;9935.05616966387;7146.571205727673;7348.1182258129365;7412.1846596875375;7413.258674132544;7421.257874212535;7382.483226174117;7470.2903224193615;7473.215312392338;7530.736424225126;7550.735724249625 15:43:20 INFO - PID 1977 | |4;prototype.html;487.3663291378822;522.9869253268669;523.8396950754079;526.4367127173924;533.0352062766393;6271.839709653717;6305.243370795511;6369.6496692681885;6459.677016149191;6482.254540727818;6285.402886725765;6329.341676495871;6334.778282760104;6352.714127864246;6365.649889256089;5222.294990176335;5225.843224703265;5250.789968401259;5256.106461901476;5285.8942821143555;4916.410030796309;5028.943921776431;5197.480251974791;5215.426303106674;5298.973505132488;4970.850874473754;4977.676451030689;5046.142155833499;5046.949530504703;5049.293098966147;4986.127427700138;5022.6233032522705;5022.773975171108;5038.345015148044;5058.924116138261;4491.326301054835;4516.390287311204;4521.457425108975;4524.502304746476;4543.863684089482;6236.594621349598;6245.281792593864;6254.906176407358;6271.874562508724;6333.46165575922;5730.4269573042775;5731.570132240098;5733.312002559678;5754.424557544254;5791.681457519835;6492.220933488012;6533.803985880431;6554.344565543455;6577.638229897354;6632.336766323329;4877.561019508261;4896.363472748555;4938.777755001033;4957.405111386656;4971.428285747149;6580.835479113012;6709.6980635871505;6862.495189255886;6961.686724097428;7075.858482830315;4830.66185367028;4958.429780575208;4967.850964471072;5014.122528557488;5030.591434398374;5020.799168033311;5079.263506791494;5119.8720031998755;5120.462351453119;5121.564667003272;5618.129189975561;5639.407862174413;5672.517835983987;5701.344345400249;5728.903833758883;6515.481639914393;6633.402993730588;6637.3030831762935;6687.732490700318;6703.581173601498;1758.5867321179512;1758.78015248094;1759.542518945058;1770.2918832466858;1778.7331900215072;1178.7406770510474;1216.975660486803;1225.5587988324373;1231.8952889004356;1249.731307768834;5706.800261990894;5714.856828744174;5715.342735585377;5726.856828579235;5784.587093614788;6731.461483081342;6765.241037330231;6767.221769496571;6767.864642707217;6875.278095799969;5675.8080803031;5678.176664383641;5723.7996670116345;5747.655140691572;5752.33848107473;3502.387082260594;3552.182997910443;3565.037439891215;3613.259281847215;3632.854685812538;6573.704183311763;6598;6609.834754131185;6610.272869984298;6658.734840380313;6518.869622607522;6867.690953907087;6869.519133660696;6942.444604431634;6942.535399933052;6392.456641185551;6417.582857114273;6501.284858665448;6502.219733631994;6540.149780528501;6614.570052946543;6674.566153200027;6729;6737.629430381376;6770.5599136056;6474;6482.189726284215;6695.497837662292;6710.563813352116;6763.323667633197;6432.9678351607945;6468.482521398183;6479.157709497735;6493.383128602775;6523.804285871432;4958.82644107469;4992.450830408579;4999.950000499948;5017.473165317735;5029.270755740324;6432.388923052303;6457.967710161419;6518.837029074311;6697.263301036979;6800.761973330909;6386.584871983307;6429.7106630200815;6489.9351006491215;6497.855707616381;6542.34576542342;6365.2361716594305;6460.353964603502;6486.54594178403;6525.804225873231;6549.515979180672;6350.90473642905;6440.259370172303;6445.677716114269;6474.805755827333;6524.869502610016;6562.146920900253;6579.506537009648;6614.966925165343;6625.238097618835;6712.6979285931275;6426.389492998158;6430.5177111715875;6438.549301548846;6453.741850326122;6461.450776684034 15:43:20 INFO - PID 1977 | |5;yui.html;899.6401439424234;910.4400793511991;914.8444764390053;919.6137622198673;921.2675922641499;19316.613667726655;19750.209991600317;19981.300654477098;19988.999999999964;19996.90001549992;18602.627947441077;18799.248030078816;18825.05874706266;18828.152733127008;18869.433916982478;956.1394744729729;966.3911735606558;967.4050458967755;968.5980318167951;968.6851773173712;987.1757082835841;988.5255077562774;988.9159421449185;990.0594435286471;991.3704797453597;992.4343124419084;1024.9180065594771;1025.354026963016;1026.5893642542967;1028.8456731490262;1014.6702321745462;1021;1021.2136654775818;1021.7547788530774;1022.6522982186056;704.8308405982527;736.054170391045;743.6207534157619;747.667288056815;748.9026426564566;19533.121009554605;19692.310769123156;19953.401397958085;19968.600627987358;19985.800141998538;20211.595768084557;20326.695099573517;21194.470138246514;21298.467538311666;21298.893505532375;17214.31142754288;17538.561535961577;17709.203085861038;17714.645707085787;17864.91067544667;3641.6904563112166;3670.5595328560476;3673.3571624965793;3674.1733110050027;3675.4854320395166;105202.60578532172;121859;124606;124803.37598312041;124922.37538812248;16672.166391680366;16862.57843553921;17006.57483562921;17201.913990429966;17295.56761080983;30547.694523054484;30594.847025764728;31033.379332413224;31501.36997260042;31945.840270798497;8744.606492707844;8771.736847894574;8780.165884240987;8834.116588341243;8869.600867960957;4136.9793151034055;4244.766537840448;4260.318349064197;4348.760818154969;4355.020120472932;5301.628885977944;5392.325959255093;5392.487713667274;5404.702741349263;5407.405185429599;1020.6886899495726;1050.2648146297622;1058.7353161709573;1065.728239298974;1066.3069005146563;684.7192651013061;697.7732237022988;701.7298340139083;702.5679207287482;707.7982774909126;12062.698432539257;12274.570390036306;12543.686407839878;12640.367981601066;12933.870661293455;677.3260605697362;681.57401623985;687.5324779150226;699.9615021173885;708.3766285668579;5735.827925162252;5777.133429985452;5801.013827649225;5806.912896306559;5809;119031;120072;120408;123054.38472807758;125922.3703881493;111013;111646.44176779065;111906;113244.34497642022;117696.41151794186;93234.60148097784;93829;94083.52958235165;94392.99999999863;95751.52124239334;10512.579496820348;10597.89402105969;10673.8932610676;10768.3538987661;10919.781604368027;43496.78251608848;43854;44125;44646.77676611596;44970.550294497945;56049.43950560442;56261;56975.715121424124;59461;59835.700821495615;15230.238488075773;15587.376504939675;15631.76552351693;15702.9214853925;15741.842581574037;16945;17081.743773843154;17139.91430042842;17306.394276200273;17336.219870106128;14016.859831401556;14037.789433158716;14140.222287774272;14219.360128794437;14267.143971361751;15320.923395382952;15345.769813452584;15447;15565.610859728597;15572.610684732972;14764.63088422798;14978.92510537484;15143.848561514244;15183.240837957837;15243;10760.300580462557;10775.299605525615;11125.276857004268;11165.609203677832;11190.272632278877;10573.418461984666;10615.946920265349;10645.946770266099;10781.514831832743;10789.082927951213 15:43:20 INFO - PID 1977 | __end_tp_report 15:43:20 INFO - PID 1977 | __start_cc_report 15:43:20 INFO - PID 1977 | _x_x_mozilla_cycle_collect,353 15:43:20 INFO - PID 1977 | __end_cc_report 15:43:20 INFO - PID 1977 | __startTimestamp1505342600347__endTimestamp 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | ------- Summary: start ------- 15:43:20 INFO - PID 1977 | Number of tests: 6 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | [#0] dojo.html Cycles:165 Average:6254.67 Median:6549.87 stddev:868.33 (13.3%) stddev-sans-first:834.75 15:43:20 INFO - PID 1977 | Values: 3090.3 3138.2 3197.0 3197.2 3204.5 6225.5 6268.0 6318.5 6457.9 6468.5 6185.9 6268.6 6322.0 6352.9 6383.6 1330.9 5813.0 5858.0 5877.4 5945.7 5681.0 5815.0 5815.6 5834.9 5879.0 5782.0 5807.1 5874.4 5875.6 5889.1 5797.2 5817.6 5848.7 5866.9 5889.4 5820.5 5822.4 5871.5 5880.8 5888.7 6161.9 6284.2 6335.4 6427.9 6463.6 6621.6 6697.8 6714.0 6721.2 6810.6 6875.4 6908.1 6932.9 6940.8 6966.9 6209.4 6286.9 6355.0 6373.8 6409.6 7130.3 7161.2 7163.4 7167.1 7167.6 6713.7 6721.6 6724.1 6814.5 6819.0 6642.0 6770.6 6784.1 6901.2 6925.3 6813.2 6842.4 6847.1 6847.8 6852.6 6773.4 6883.6 6947.8 6950.5 6951.2 5280.8 5288.4 5356.1 5362.5 5363.5 4499.9 4635.7 4643.1 4676.0 4704.2 5759.9 5771.0 5806.1 5869.6 5874.4 6906.0 6941.6 6981.1 6995.8 7010.6 5845.3 5854.0 5930.2 5945.0 5946.2 5810.4 5855.1 5856.4 5883.6 5954.5 6649.3 6666.8 6671.3 6673.9 6709.1 6842.7 6843.6 6850.2 6929.8 6935.0 6610.5 6640.2 6701.2 6713.7 6717.9 6535.9 6624.6 6743.1 6760.4 6812.8 6739.4 6971.5 6981.3 6981.7 6982.5 6477.8 6517.2 6746.2 6760.6 6767.3 6641.5 6644.4 6722.9 6744.0 6772.1 6416.4 6479.4 6487.7 6555.5 6657.9 6592.3 6649.3 6664.8 6699.6 6860.5 6500.7 6549.9 6569.9 6577.0 6597.6 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | [#1] ext.html Cycles:180 Average:37148.65 Median:19393.10 stddev:52536.45 (270.9%) stddev-sans-first:52613.01 15:43:20 INFO - PID 1977 | Values: 820.7 842.8 844.0 844.0 845.4 30726.4 33592.5 33639.5 33672.8 33830.3 38823.8 39162.0 39286.8 39338.4 39343.8 6943.9 7041.9 7044.9 7682.8 7861.7 7711.1 7813.9 7823.8 7824.0 7834.7 7776.7 7793.3 7814.4 7819.1 7840.6 7743.0 7779.6 7793.4 7808.7 7836.4 7721.0 7776.8 7784.9 7787.3 7788.6 37042.6 37230.5 37329.4 37391.6 37473.3 3504.0 3542.9 3545.6 3549.3 3550.8 31047.8 31131.2 31165.8 31174.7 31288.2 5061.5 5077.5 5101.2 5102.5 5136.5 182799.0 182972.0 183285.0 184105.0 184801.0 43131.6 47579.8 47680.0 47855.3 47899.3 45861.8 51576.5 51985.0 52105.0 52125.0 13937.3 14008.9 14039.0 14052.8 14075.4 11205.3 11249.7 11257.0 11262.5 11298.8 14367.9 14389.3 14435.2 14498.6 14545.7 5680.5 5712.9 5760.8 5768.6 5908.6 982.6 999.3 1001.0 1001.8 1007.2 23080.7 23086.7 23144.0 23154.4 23202.5 1078.4 1082.1 1090.2 1098.7 1114.1 12056.5 12084.2 12121.2 12161.3 12299.3 223932.0 225321.0 225601.0 225604.0 226204.0 52666.0 52888.5 52955.2 52983.2 53072.5 1418.6 1449.6 1458.2 1461.2 1462.8 22558.3 22690.8 22718.5 22942.2 23291.8 37118.4 37157.0 37195.0 37219.8 37252.0 196787.0 196992.0 197527.0 197595.0 197668.0 33390.7 35243.3 36967.6 37038.0 37220.4 45520.8 46657.0 46673.8 46693.3 46776.3 33891.0 34002.5 34362.0 34531.3 35062.8 33131.7 33916.8 33964.2 33983.5 34332.5 38260.2 38554.2 38560.6 38658.8 38952.6 33275.5 33412.0 33432.7 33626.2 34122.5 24611.1 24624.0 24713.6 24906.0 24927.3 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | [#2] jquery.html Cycles:180 Average:33609.19 Median:22839.83 stddev:81104.16 (355.1%) stddev-sans-first:81293.72 15:43:20 INFO - PID 1977 | Values: 560.4 575.2 580.9 592.7 605.2 22273.2 22913.7 22988.5 23012.5 23026.0 21306.6 21409.1 21456.7 23293.0 24564.6 6194.1 6409.5 6415.5 6417.4 6437.0 6221.3 6292.2 6329.0 6334.5 6335.7 6576.7 6581.1 6582.0 6585.9 6586.2 6224.4 6563.9 6565.7 6576.9 6578.2 6007.2 6033.4 6037.2 6037.7 6038.5 28371.4 28853.9 28864.4 28881.0 28939.4 2050.5 2069.7 2077.1 2078.3 2079.2 17742.2 18139.6 18383.4 18545.7 18643.0 2338.8 2346.7 2382.2 2395.2 2398.8 119973.4 125089.4 128882.0 129476.4 133787.0 14278.2 14452.2 14604.9 14630.3 14634.3 22599.3 22706.0 22707.9 22719.2 22766.0 9606.9 9872.8 9882.3 9933.3 9981.2 9027.2 9066.6 9079.5 9175.8 9185.2 9008.5 9014.6 9065.6 9070.9 9132.3 2428.4 2446.0 2453.2 2516.6 2637.7 930.4 939.5 944.4 953.3 955.9 17907.2 18532.6 18642.5 18819.5 18896.3 985.5 995.2 999.2 1003.2 1006.0 10149.7 10265.6 10308.9 10391.9 10623.5 477957.0 483087.0 483879.0 484445.0 488388.0 108280.0 108753.5 110150.4 110488.0 110606.0 1114.8 1122.3 1141.5 1143.3 1148.1 16673.1 16829.5 16881.1 16912.4 17011.5 54113.5 54271.7 55491.0 55648.2 56110.7 68419.3 68539.0 69383.7 69445.3 69751.0 13829.2 13907.4 13948.5 13981.4 14014.3 21724.6 21827.3 22019.2 22041.8 22056.8 17386.4 17668.6 17774.9 17787.4 17793.6 15914.8 16083.1 16326.7 16426.8 16669.4 17801.3 17973.3 17977.6 18185.3 18250.3 13629.9 13735.1 13757.2 13758.7 13813.8 17473.3 17682.5 17716.6 17809.0 17883.0 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | [#3] mootools.html Cycles:180 Average:9237.76 Median:2610.55 stddev:6578.73 (252.0%) stddev-sans-first:6570.08 15:43:20 INFO - PID 1977 | Values: 1290.3 1358.2 1362.1 1379.1 1382.9 15358.5 15841.4 15865.4 15875.4 15882.8 15459.0 15558.8 15581.5 15595.2 15602.7 2707.1 2731.9 2743.9 2764.6 2769.4 2512.8 2607.1 2610.3 2610.8 2638.3 2422.8 2563.6 2565.7 2568.6 2569.2 2696.3 2697.1 2724.7 2739.3 2741.5 2650.6 2655.8 2729.4 2761.3 2780.5 14535.9 14755.3 14755.4 14756.3 14771.1 7957.7 8216.8 8228.8 8230.1 8240.6 4749.7 4953.1 5011.4 5018.2 5044.4 1870.3 1891.4 1893.6 1954.9 1961.5 25277.6 25744.7 25955.9 26443.7 26500.2 6727.3 6754.2 6769.4 6942.9 6970.2 16267.6 16852.6 17425.1 17435.9 17453.7 7883.7 8061.0 8062.8 8094.0 8098.3 7312.0 7410.7 7419.9 7424.7 7455.9 5165.5 5295.8 5316.3 5324.4 5345.2 1545.1 1550.2 1552.6 1572.9 1579.8 882.1 901.2 904.4 909.0 910.2 7715.7 7783.3 7826.9 7828.7 7871.6 696.7 698.1 714.3 716.4 718.3 4581.1 4614.0 4880.4 4918.6 5007.1 19844.5 21062.9 21654.5 21658.1 21870.6 20129.8 20264.9 21084.4 21210.7 21263.3 11330.5 11465.5 11500.0 11504.1 11565.4 8229.9 8247.8 8256.7 8257.2 8280.7 17980.2 18010.1 18234.8 18536.6 18655.2 18555.6 18633.2 18660.3 18748.3 18845.1 10454.3 10652.4 10653.0 10653.4 10670.4 13671.2 13766.2 13867.5 13928.1 14014.1 11325.0 11338.0 11474.3 11619.1 11640.9 10840.7 10864.6 11199.5 11222.3 11292.2 9770.8 9779.6 9796.0 9856.7 9935.1 7146.6 7348.1 7412.2 7413.3 7421.3 7382.5 7470.3 7473.2 7530.7 7550.7 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | [#4] prototype.html Cycles:180 Average:5539.31 Median:6295.32 stddev:1544.12 (24.5%) stddev-sans-first:1501.17 15:43:20 INFO - PID 1977 | Values: 487.4 523.0 523.8 526.4 533.0 6271.8 6305.2 6369.6 6459.7 6482.3 6285.4 6329.3 6334.8 6352.7 6365.6 5222.3 5225.8 5250.8 5256.1 5285.9 4916.4 5028.9 5197.5 5215.4 5299.0 4970.9 4977.7 5046.1 5046.9 5049.3 4986.1 5022.6 5022.8 5038.3 5058.9 4491.3 4516.4 4521.5 4524.5 4543.9 6236.6 6245.3 6254.9 6271.9 6333.5 5730.4 5731.6 5733.3 5754.4 5791.7 6492.2 6533.8 6554.3 6577.6 6632.3 4877.6 4896.4 4938.8 4957.4 4971.4 6580.8 6709.7 6862.5 6961.7 7075.9 4830.7 4958.4 4967.9 5014.1 5030.6 5020.8 5079.3 5119.9 5120.5 5121.6 5618.1 5639.4 5672.5 5701.3 5728.9 6515.5 6633.4 6637.3 6687.7 6703.6 1758.6 1758.8 1759.5 1770.3 1778.7 1178.7 1217.0 1225.6 1231.9 1249.7 5706.8 5714.9 5715.3 5726.9 5784.6 6731.5 6765.2 6767.2 6767.9 6875.3 5675.8 5678.2 5723.8 5747.7 5752.3 3502.4 3552.2 3565.0 3613.3 3632.9 6573.7 6598.0 6609.8 6610.3 6658.7 6518.9 6867.7 6869.5 6942.4 6942.5 6392.5 6417.6 6501.3 6502.2 6540.1 6614.6 6674.6 6729.0 6737.6 6770.6 6474.0 6482.2 6695.5 6710.6 6763.3 6433.0 6468.5 6479.2 6493.4 6523.8 4958.8 4992.5 5000.0 5017.5 5029.3 6432.4 6458.0 6518.8 6697.3 6800.8 6386.6 6429.7 6489.9 6497.9 6542.3 6365.2 6460.4 6486.5 6525.8 6549.5 6350.9 6440.3 6445.7 6474.8 6524.9 6562.1 6579.5 6615.0 6625.2 6712.7 6426.4 6430.5 6438.5 6453.7 6461.5 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | [#5] yui.html Cycles:180 Average:23737.34 Median:19612.72 stddev:33866.03 (172.7%) stddev-sans-first:33917.62 15:43:20 INFO - PID 1977 | Values: 899.6 910.4 914.8 919.6 921.3 19316.6 19750.2 19981.3 19989.0 19996.9 18602.6 18799.2 18825.1 18828.2 18869.4 956.1 966.4 967.4 968.6 968.7 987.2 988.5 988.9 990.1 991.4 992.4 1024.9 1025.4 1026.6 1028.8 1014.7 1021.0 1021.2 1021.8 1022.7 704.8 736.1 743.6 747.7 748.9 19533.1 19692.3 19953.4 19968.6 19985.8 20211.6 20326.7 21194.5 21298.5 21298.9 17214.3 17538.6 17709.2 17714.6 17864.9 3641.7 3670.6 3673.4 3674.2 3675.5 105202.6 121859.0 124606.0 124803.4 124922.4 16672.2 16862.6 17006.6 17201.9 17295.6 30547.7 30594.8 31033.4 31501.4 31945.8 8744.6 8771.7 8780.2 8834.1 8869.6 4137.0 4244.8 4260.3 4348.8 4355.0 5301.6 5392.3 5392.5 5404.7 5407.4 1020.7 1050.3 1058.7 1065.7 1066.3 684.7 697.8 701.7 702.6 707.8 12062.7 12274.6 12543.7 12640.4 12933.9 677.3 681.6 687.5 700.0 708.4 5735.8 5777.1 5801.0 5806.9 5809.0 119031.0 120072.0 120408.0 123054.4 125922.4 111013.0 111646.4 111906.0 113244.3 117696.4 93234.6 93829.0 94083.5 94393.0 95751.5 10512.6 10597.9 10673.9 10768.4 10919.8 43496.8 43854.0 44125.0 44646.8 44970.6 56049.4 56261.0 56975.7 59461.0 59835.7 15230.2 15587.4 15631.8 15702.9 15741.8 16945.0 17081.7 17139.9 17306.4 17336.2 14016.9 14037.8 14140.2 14219.4 14267.1 15320.9 15345.8 15447.0 15565.6 15572.6 14764.6 14978.9 15143.8 15183.2 15243.0 10760.3 10775.3 11125.3 11165.6 11190.3 10573.4 10615.9 10645.9 10781.5 10789.1 15:43:20 INFO - PID 1977 | -------- Summary: end -------- 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | 15:43:20 INFO - PID 1977 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 15:43:20 INFO - PID 1977 | 15:43:21 INFO - TEST-INFO | 1977: exit 0 15:43:21 INFO - TEST-OK | dromaeo_css | took 1110087ms 15:43:21 INFO - TEST-START | kraken 15:43:21 INFO - Initialising browser for kraken test... 15:43:21 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45856/getInfo.html -profile /tmp/tmp_3r1Md/profile 15:43:21 INFO - TEST-INFO | started process 2350 (/builds/slave/test/build/application/firefox/firefox http://localhost:45856/getInfo.html) 15:43:27 INFO - TEST-INFO | 2350: exit 0 15:43:27 INFO - Browser initialized. 15:43:27 INFO - Running cycle 1/1 for kraken test... 15:43:27 INFO - Using env: {'DISPLAY': ':0', 15:43:27 INFO - 'HOME': '/home/cltbld', 15:43:27 INFO - 'JSGC_DISABLE_POISONING': '1', 15:43:27 INFO - 'LANG': 'en_US.UTF-8', 15:43:27 INFO - 'LANGUAGE': 'en_US:en', 15:43:27 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:43:27 INFO - 'LOGNAME': 'cltbld', 15:43:27 INFO - 'MAIL': '/var/mail/cltbld', 15:43:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:43:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:43:27 INFO - 'MOZ_CRASHREPORTER': '1', 15:43:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:27 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:43:27 INFO - 'MOZ_NO_REMOTE': '1', 15:43:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:43:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:43:27 INFO - 'NO_EM_RESTART': '1', 15:43:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:43:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:27 INFO - 'PWD': '/builds/slave/test', 15:43:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:43:27 INFO - 'RUST_BACKTRACE': 'full', 15:43:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:43:27 INFO - 'SHELL': '/bin/bash', 15:43:27 INFO - 'SHLVL': '1', 15:43:27 INFO - 'TERM': 'linux', 15:43:27 INFO - 'TMOUT': '86400', 15:43:27 INFO - 'USER': 'cltbld', 15:43:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056', 15:43:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:27 INFO - '_': '/tools/buildbot/bin/python'} 15:43:27 INFO - TEST-INFO | started process 2585 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_3r1Md/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:43:28 INFO - PID 2585 | 15:43:28 INFO - PID 2585 | (/builds/slave/test/build/application/firefox/firefox:2707): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:43:28 INFO - PID 2585 | 15:43:38 INFO - PID 2585 | RSS: Main: 170872832 15:43:38 INFO - PID 2585 | 15:43:41 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45856/tests/kraken/driver.html?testName=audio-beat-detection) 15:43:42 INFO - PID 2585 | RSS: Main: 175841280 15:43:42 INFO - PID 2585 | 15:43:44 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45856/tests/kraken/driver.html?testName=audio-dft) 15:43:45 INFO - PID 2585 | RSS: Main: 175247360 15:43:45 INFO - PID 2585 | 15:43:48 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45856/tests/kraken/driver.html?testName=audio-fft) 15:43:49 INFO - PID 2585 | RSS: Main: 171483136 15:43:49 INFO - PID 2585 | 15:43:51 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45856/tests/kraken/driver.html?testName=audio-oscillator) 15:43:51 INFO - PID 2585 | RSS: Main: 172400640 15:43:51 INFO - PID 2585 | 15:43:53 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45856/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:43:54 INFO - PID 2585 | RSS: Main: 172601344 15:43:54 INFO - PID 2585 | 15:43:57 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45856/tests/kraken/driver.html?testName=imaging-darkroom) 15:43:58 INFO - PID 2585 | RSS: Main: 175239168 15:43:58 INFO - PID 2585 | 15:44:02 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45856/tests/kraken/driver.html?testName=imaging-desaturate) 15:44:02 INFO - PID 2585 | RSS: Main: 175226880 15:44:02 INFO - PID 2585 | 15:44:05 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45856/tests/kraken/driver.html?testName=json-parse-financial) 15:44:06 INFO - PID 2585 | RSS: Main: 176046080 15:44:06 INFO - PID 2585 | 15:44:07 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45856/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:44:08 INFO - PID 2585 | RSS: Main: 175038464 15:44:08 INFO - PID 2585 | 15:44:10 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45856/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:44:10 INFO - PID 2585 | RSS: Main: 175325184 15:44:10 INFO - PID 2585 | 15:44:12 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45856/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:44:13 INFO - PID 2585 | RSS: Main: 175329280 15:44:13 INFO - PID 2585 | 15:44:16 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45856/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:44:16 INFO - PID 2585 | RSS: Main: 175452160 15:44:16 INFO - PID 2585 | 15:44:19 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45856/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:44:20 INFO - PID 2585 | RSS: Main: 175788032 15:44:20 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | Cycle 1(1): loaded http://localhost:45856/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45856/tests/kraken/driver.html?testName=ai-astar) 15:44:22 INFO - PID 2585 | RSS: Main: 177332224 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | __start_tp_report 15:44:22 INFO - PID 2585 | _x_x_mozilla_page_load 15:44:22 INFO - PID 2585 | _x_x_mozilla_page_load_details 15:44:22 INFO - PID 2585 | |i|pagename|runs| 15:44:22 INFO - PID 2585 | |0;ai-astar;107;106;107;106;107;110;107;107;107;106 15:44:22 INFO - PID 2585 | |1;audio-beat-detection;114;115;116;115;119;117;116;145;119;116 15:44:22 INFO - PID 2585 | |2;audio-dft;162;161;160;162;161;162;162;166;167;161 15:44:22 INFO - PID 2585 | |3;audio-fft;75;74;72;74;71;74;74;73;75;76 15:44:22 INFO - PID 2585 | |4;audio-oscillator;82;83;86;78;79;80;80;84;77;82 15:44:22 INFO - PID 2585 | |5;imaging-gaussian-blur;113;113;114;113;113;113;111;113;110;115 15:44:22 INFO - PID 2585 | |6;imaging-darkroom;148;147;148;147;148;149;149;149;148;147 15:44:22 INFO - PID 2585 | |7;imaging-desaturate;94;94;95;94;95;94;94;95;95;94 15:44:22 INFO - PID 2585 | |8;json-parse-financial;54;53;52;50;52;52;52;53;53;54 15:44:22 INFO - PID 2585 | |9;json-stringify-tinderbox;38;38;38;39;38;38;38;39;38;38 15:44:22 INFO - PID 2585 | |10;stanford-crypto-aes;78;75;72;74;75;75;76;76;73;72 15:44:22 INFO - PID 2585 | |11;stanford-crypto-ccm;112;111;117;116;116;119;112;108;114;118 15:44:22 INFO - PID 2585 | |12;stanford-crypto-pbkdf2;172;170;171;166;166;167;176;165;174;172 15:44:22 INFO - PID 2585 | |13;stanford-crypto-sha256-iterative;67;64;64;66;67;65;65;69;66;64 15:44:22 INFO - PID 2585 | __end_tp_report 15:44:22 INFO - PID 2585 | __start_cc_report 15:44:22 INFO - PID 2585 | _x_x_mozilla_cycle_collect,949 15:44:22 INFO - PID 2585 | __end_cc_report 15:44:22 INFO - PID 2585 | __startTimestamp1505342662391__endTimestamp 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | ------- Summary: start ------- 15:44:22 INFO - PID 2585 | Number of tests: 14 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#0] ai-astar Cycles:10 Average:107.00 Median:107.00 stddev:1.15 (1.1%) stddev-sans-first:1.22 15:44:22 INFO - PID 2585 | Values: 107.0 106.0 107.0 106.0 107.0 110.0 107.0 107.0 107.0 106.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#1] audio-beat-detection Cycles:10 Average:119.20 Median:116.50 stddev:9.21 (7.9%) stddev-sans-first:9.58 15:44:22 INFO - PID 2585 | Values: 114.0 115.0 116.0 115.0 119.0 117.0 116.0 145.0 119.0 116.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#2] audio-dft Cycles:10 Average:162.40 Median:162.00 stddev:2.27 (1.4%) stddev-sans-first:2.40 15:44:22 INFO - PID 2585 | Values: 162.0 161.0 160.0 162.0 161.0 162.0 162.0 166.0 167.0 161.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#3] audio-fft Cycles:10 Average:73.80 Median:74.00 stddev:1.48 (2.0%) stddev-sans-first:1.50 15:44:22 INFO - PID 2585 | Values: 75.0 74.0 72.0 74.0 71.0 74.0 74.0 73.0 75.0 76.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:82.00 stddev:2.81 (3.4%) stddev-sans-first:2.96 15:44:22 INFO - PID 2585 | Values: 82.0 83.0 86.0 78.0 79.0 80.0 80.0 84.0 77.0 82.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:1.40 (1.2%) stddev-sans-first:1.48 15:44:22 INFO - PID 2585 | Values: 113.0 113.0 114.0 113.0 113.0 113.0 111.0 113.0 110.0 115.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 15:44:22 INFO - PID 2585 | Values: 148.0 147.0 148.0 147.0 148.0 149.0 149.0 149.0 148.0 147.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 15:44:22 INFO - PID 2585 | Values: 94.0 94.0 95.0 94.0 95.0 94.0 94.0 95.0 95.0 94.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:1.18 (2.2%) stddev-sans-first:1.12 15:44:22 INFO - PID 2585 | Values: 54.0 53.0 52.0 50.0 52.0 52.0 52.0 53.0 53.0 54.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 15:44:22 INFO - PID 2585 | Values: 38.0 38.0 38.0 39.0 38.0 38.0 38.0 39.0 38.0 38.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:75.00 stddev:1.90 (2.5%) stddev-sans-first:1.56 15:44:22 INFO - PID 2585 | Values: 78.0 75.0 72.0 74.0 75.0 75.0 76.0 76.0 73.0 72.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#11] stanford-crypto-ccm Cycles:10 Average:114.30 Median:116.00 stddev:3.50 (3.0%) stddev-sans-first:3.61 15:44:22 INFO - PID 2585 | Values: 112.0 111.0 117.0 116.0 116.0 119.0 112.0 108.0 114.0 118.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:171.50 stddev:3.75 (2.2%) stddev-sans-first:3.91 15:44:22 INFO - PID 2585 | Values: 172.0 170.0 171.0 166.0 166.0 167.0 176.0 165.0 174.0 172.0 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.64 (2.5%) stddev-sans-first:1.67 15:44:22 INFO - PID 2585 | Values: 67.0 64.0 64.0 66.0 67.0 65.0 65.0 69.0 66.0 64.0 15:44:22 INFO - PID 2585 | -------- Summary: end -------- 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 15:44:22 INFO - PID 2585 | 15:44:22 INFO - PID 2585 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 15:44:23 INFO - TEST-INFO | 2585: exit 0 15:44:23 INFO - TEST-OK | kraken | took 62038ms 15:44:23 INFO - SUITE-END | took 1172s 15:44:23 INFO - Completed test suite (00:19:32) 15:44:23 INFO - javascript benchmark 15:44:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8428.948039796789, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3090.3201295714944, 3138.152698771332, 3196.9929472216236, 3197.232664160599, 3204.47126224173, 6225.501959843213, 6267.968660156693, 6318.494520438366, 6457.8708425831455, 6468.450181734553, 6185.907211391822, 6268.592541484801, 6321.968390158055, 6352.904706429395, 6383.648899310536, 1330.9005044542234, 5813.040848260032, 5858.0, 5877.412258774109, 5945.732442040118, 5680.9715951420185, 5815.040518314473, 5815.62198457101, 5834.854128646775, 5879.0, 5782.0, 5807.070868661036, 5874.383189765099, 5875.559333050017, 5889.146073819272, 5797.159411885295, 5817.56368272377, 5848.707564621752, 5866.853328666774, 5889.381614930457, 5820.505257053155, 5822.359540450547, 5871.530277577784, 5880.764769409218, 5888.676122813244, 6161.921663708833, 6284.183056202664, 6335.3981371769605, 6427.87144257117, 6463.612183268972, 6621.602703837785, 6697.765578204788, 6714.0, 6721.227058888241, 6810.62541560214, 6875.449964002868, 6908.1364829396325, 6932.896006559906, 6940.826479338007, 6966.860662786767, 6209.410106039919, 6286.9056964145575, 6355.046742988542, 6373.776917807901, 6409.647469389181, 7130.251323610946, 7161.248068952685, 7163.3552980232025, 7167.104111986002, 7167.641617919083, 6713.664316784141, 6721.596704197764, 6724.058631791553, 6814.522983391115, 6819.0, 6641.966790166019, 6770.627615481098, 6784.118064651662, 6901.240863505011, 6925.342092501204, 6813.182418109858, 6842.4183944364295, 6847.109875716125, 6847.794566163023, 6852.6231057292325, 6773.424258938046, 6883.621400823003, 6947.826304342432, 6950.478714096463, 6951.2353641099435, 5280.762365693553, 5288.365396152486, 5356.143017117242, 5362.544183744348, 5363.490468405495, 4499.88750281239, 4635.675502714777, 4643.071385722869, 4675.976620116946, 4704.2473204287835, 5759.913601295984, 5770.971145144332, 5806.071028635398, 5869.647821130746, 5874.353821079678, 6905.965470172617, 6941.618210998444, 6981.127359080115, 6995.755148569878, 7010.649467526603, 5845.298564172326, 5853.970730146322, 5930.229070220844, 5945.048792193228, 5946.197263369476, 5810.360860305363, 5855.063189889597, 5856.385079566669, 5883.558733094949, 5954.49386802118, 6649.268580456146, 6666.83332916681, 6671.299513551105, 6673.899891501631, 6709.127813384229, 6842.692078856563, 6843.589384636938, 6850.177978642595, 6929.826754331081, 6934.965325173342, 6610.504212184009, 6640.236372816995, 6701.229358623821, 6713.731450741916, 6717.899231511434, 6535.869282614226, 6624.635645039376, 6743.055972163999, 6760.357766012221, 6812.795616131524, 6739.393454589111, 6971.477139214478, 6981.301869812978, 6981.650917454209, 6982.546134501445, 6477.838054048687, 6517.152770139852, 6746.190457145174, 6760.628165450948, 6767.255601883888, 6641.535092543669, 6644.4020038196795, 6722.932770672231, 6744.0, 6772.119624448987, 6416.358364163733, 6479.416852483299, 6487.675616219264, 6555.475561954937, 6657.933420665731, 6592.307807680125, 6649.335066493312, 6664.766733164316, 6699.631520266432, 6860.519763616699, 6500.674966251763, 6549.8690026200165, 6569.9014514783275, 6577.0, 6597.60414375139], "value": 6189.513386576019, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.7455688736492, 842.8356470488253, 844.0209357145708, 844.0420123160211, 845.3701992015946, 30726.38547229054, 33592.49611255833, 33639.49540756887, 33672.831635841845, 33830.32339353218, 38823.805880970554, 39162.0, 39286.8035659822, 39338.40992385116, 39343.80328098363, 6943.930560694392, 7041.894371584431, 7044.894326585079, 7682.769516914474, 7861.685532578718, 7711.11322197946, 7813.921860781376, 7823.804404889866, 7824.0, 7834.72578459754, 7776.65005074773, 7793.337566306871, 7814.413918956073, 7819.139894611588, 7840.607969601526, 7742.961285193566, 7779.611019449033, 7793.376529877596, 7808.687652493893, 7836.412269079842, 7721.0, 7776.844463110706, 7784.883226751604, 7787.29914307715, 7788.571628560427, 37042.62957370446, 37230.51077956879, 37329.440058399145, 37391.62608373936, 37473.25053498942, 3503.964960350415, 3542.9114272143147, 3545.556805399325, 3549.2546565221332, 3550.751447398683, 31047.84476077628, 31131.221719457193, 31165.844170779, 31174.688253117405, 31288.21779455509, 5061.468545802712, 5077.466865979056, 5101.183810590288, 5102.540771330561, 5136.4606716295, 182799.0, 182972.0, 183285.0, 184105.0, 184800.99999999866, 43131.56868431338, 47579.76210118927, 47680.0, 47855.28217076747, 47899.281510777364, 45861.77069114633, 51576.48423515792, 51985.0, 52105.0, 52125.0, 13937.303134843218, 14008.85991140096, 14039.017268791291, 14052.789208161887, 14075.366608502642, 11205.271657342246, 11249.66251012471, 11257.0, 11262.493187806405, 11298.830517542243, 14367.928160359132, 14389.28053597316, 14435.206063666388, 14498.637534061732, 14545.709085818224, 5680.528969898025, 5712.914306285409, 5760.794619927393, 5768.625039372428, 5908.586398952032, 982.6266018912768, 999.2954966748425, 1001.0340021878922, 1001.8096561653408, 1007.1590222164428, 23080.65379019316, 23086.65370019451, 23144.0, 23154.42113947131, 23202.535949281257, 1078.4338222433128, 1082.1396989393452, 1090.2477290669412, 1098.6923661374828, 1114.1198453222025, 12056.517739290506, 12084.214526055779, 12121.21212121227, 12161.33112678811, 12299.323537205539, 223932.0, 225321.0, 225601.0, 225604.0, 226204.0, 52666.0, 52888.471115289125, 52955.205671914184, 52983.20525192125, 53072.46927530752, 1418.5744276716944, 1449.5506393018197, 1458.183417286323, 1461.167134733213, 1462.7659574468034, 22558.323250302517, 22690.773092268864, 22718.545629086995, 22942.197023104112, 23291.76708232964, 37118.44322335221, 37157.0, 37195.0, 37219.8139009314, 37252.0, 196787.0, 196992.0, 197527.0, 197595.0, 197668.0, 33390.666093338754, 35243.29513409666, 36967.630323696416, 37038.0, 37220.44169337516, 45520.77239613781, 46657.0, 46673.766631166625, 46693.29960050534, 46776.29835552401, 33891.0, 34002.489962651074, 34361.969140925816, 34531.30937381289, 35062.82468587641, 33131.668683312855, 33916.83041584776, 33964.150896227795, 33983.49024764581, 34332.48501272533, 38260.234795304495, 38554.22891542209, 38560.61439385683, 38658.806705967414, 38952.610473896035, 33275.500867486524, 33412.0, 33432.665673342955, 33626.159346016546, 34122.48816267708, 24611.12273673934, 24624.0, 24713.629295560222, 24906.0, 24927.252182434557], "value": 16996.784401599212, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.4171661472067, 575.1959198905337, 580.8809194115207, 592.7125344208059, 605.2404232687976, 22273.220437284697, 22913.656295155564, 22988.540229195405, 23012.539749205007, 23026.0, 21306.57386852266, 21409.14363425465, 21456.678149827763, 23292.951817168225, 24564.63153052706, 6194.101855230989, 6409.519286053542, 6415.518836087312, 6417.422431981121, 6436.967815160941, 6221.284552276478, 6292.2134733158355, 6329.0, 6334.461570766491, 6335.651539165344, 6576.736930522798, 6581.111549940744, 6581.967090164567, 6585.934140658604, 6586.176727909012, 6224.408681175327, 6563.901541476882, 6565.671716414208, 6576.868462630745, 6578.177727784027, 6007.158997740276, 6033.3664965178905, 6037.184980027684, 6037.72830222641, 6038.456538911475, 28371.432571348665, 28853.85573072142, 28864.422711545652, 28881.0, 28939.42121157586, 2050.5283784729445, 2069.7412823397076, 2077.1483691686485, 2078.251829341436, 2079.1683326669304, 17742.20160092786, 18139.637207255913, 18383.356582519547, 18545.721814172797, 18643.0, 2338.824588155895, 2346.6831977682964, 2382.2257766226044, 2395.1856368834506, 2398.784109430142, 119973.40013299965, 125089.37455312756, 128882.0, 129476.35261823724, 133787.0, 14278.2146981917, 14452.205128717811, 14604.926975365055, 14630.26848657563, 14634.268286585628, 22599.322020339416, 22706.0, 22707.88646056759, 22719.204827831276, 22766.0, 9606.855897161548, 9872.753181170528, 9882.256516871797, 9933.25500587459, 9981.151602113901, 9027.187553120251, 9066.637334506679, 9079.500627465553, 9175.77060573491, 9185.173334399937, 9008.549572521348, 9014.59434325457, 9065.59204835784, 9070.909290907139, 9132.269418446642, 2428.3564855313357, 2445.9755402446103, 2453.1659235859893, 2516.5721827289403, 2637.6571045763926, 930.3673502018557, 939.5208443693765, 944.419182202954, 953.2945620240972, 955.9283053770995, 17907.1941762621, 18532.629347413247, 18642.533936651693, 18819.52951176204, 18896.338628148224, 985.5417230987625, 995.2187532786728, 999.1706883286854, 1003.19744204636, 1005.969820905374, 10149.695509134737, 10265.589376425009, 10308.896911030944, 10391.948040259751, 10623.468826558796, 477957.0, 483087.0, 483879.0, 484445.0, 488388.0, 108280.0, 108753.45623271991, 110150.44924775325, 110488.0, 110606.0, 1114.804909140897, 1122.2873475343054, 1141.49774099396, 1143.3311512765126, 1148.0700632487712, 16673.082980436193, 16829.495115146565, 16881.07154106536, 16912.408065717642, 17011.48965531036, 54113.458865410845, 54271.72864135654, 55491.0, 55648.16527752168, 56110.71944640251, 68419.31580684129, 68539.0, 69383.65308173427, 69445.30554694388, 69751.0, 13829.239391833546, 13907.37416816266, 13948.511802086878, 13981.370838312503, 14014.299285035504, 21724.565508690055, 21827.345179644635, 22019.22932697348, 22041.779582204614, 22056.779432206113, 17386.391476298268, 17668.64662706713, 17774.91112544429, 17787.377441789475, 17793.55516112056, 15914.761278581062, 16083.126947351826, 16326.673466530365, 16426.753598695792, 16669.41657042046, 17801.287948481917, 17973.281068757104, 17977.640447190723, 18185.272589096287, 18250.269989200282, 13629.931850340685, 13735.107218031195, 13757.17456952586, 13758.724825503234, 13813.792793108312, 17473.301067957647, 17682.469525914243, 17716.557086072953, 17809.0, 17883.0], "value": 11560.842614967989, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1290.341925617935, 1358.1715153756206, 1362.1486570893192, 1379.08290986494, 1382.8548002459747, 15358.462453814105, 15841.445549405758, 15865.444709435173, 15875.444359447421, 15882.761758573602, 15459.0, 15558.766618500731, 15581.454649087284, 15595.220238988062, 15602.687946241096, 2707.10665480392, 2731.877065532051, 2743.890244390222, 2764.626775385317, 2769.4461107778425, 2512.836665616738, 2607.074488556554, 2610.2560770180503, 2610.843349399042, 2638.261286839678, 2422.806175505964, 2563.5898256278997, 2565.6921169459692, 2568.5890257558794, 2569.2035469004577, 2696.312440327714, 2697.069511018715, 2724.741149590786, 2739.328864428218, 2741.4928238275884, 2650.615660729203, 2655.8406495610325, 2729.372244383783, 2761.254461295439, 2780.471710375022, 14535.854641453556, 14755.336009879582, 14755.409783608642, 14756.335964881606, 14771.113733176044, 7957.681692732283, 8216.83566328676, 8228.753137405887, 8230.135835737221, 8240.62917168723, 4749.715017098985, 4953.108440480712, 5011.423686276088, 5018.247262910592, 5044.369453818273, 1870.3360307090923, 1891.4042076745918, 1893.5644801695687, 1954.9120289587004, 1961.460598335455, 25277.620835687478, 25744.742552574422, 25955.870220648965, 26443.73556264432, 26500.204993850217, 6727.293634168342, 6754.155730533684, 6769.3907548320885, 6942.895856562156, 6970.233274339818, 16267.593310167103, 16852.57868553296, 17425.12874356277, 17435.912820435817, 17453.7381939271, 7883.6846526138315, 8061.0, 8062.798430039296, 8093.959530202312, 8098.31164351025, 7312.0, 7410.703571857173, 7419.85160296791, 7424.665890034961, 7455.888161677579, 5165.48345165488, 5295.788168473295, 5316.3354580677415, 5324.440933701983, 5345.224942383333, 1545.127003243164, 1550.2481296571154, 1552.5808031831343, 1572.9134897580514, 1579.8025246844145, 882.0870399136991, 901.1889299630385, 904.3985749476525, 908.9727308180766, 910.1853840812436, 7715.652795624211, 7783.29950304464, 7826.882596761053, 7828.686852525835, 7871.645775940096, 696.7352406085756, 698.0889938630137, 714.2999660807245, 716.4197000429668, 718.3463048625725, 4581.075344005208, 4613.953860461353, 4880.41435027799, 4918.5573298403315, 5007.1487847065355, 19844.503887402643, 21062.894685526473, 21654.458638534164, 21658.133674653152, 21870.562588748136, 20129.79870201279, 20264.89867550682, 21084.367468975954, 21210.681839772416, 21263.255786047415, 11330.490127944262, 11465.452163957925, 11500.0, 11504.079673625922, 11565.421728913521, 8229.876551851607, 8247.752567422987, 8256.711015114442, 8257.21556452136, 8280.71017514384, 17980.190891409657, 18010.099495024933, 18234.817651823312, 18536.629267414308, 18655.16051777646, 18555.628887421906, 18633.161507731915, 18660.253589856253, 18748.250069997048, 18845.057747112314, 10454.268201226145, 10652.414117223318, 10653.0, 10653.360798352123, 10670.35977841332, 13671.248081355621, 13766.24285664298, 13867.514636987657, 13928.094673846168, 14014.089084209494, 11325.037371823488, 11338.0, 11474.254173478697, 11619.128565357463, 11640.94179529097, 10840.728981775519, 10864.619738309122, 11199.496022678835, 11222.326660400402, 11292.153088518558, 9770.755731106496, 9779.60881564758, 9795.951020244853, 9856.730092668671, 9935.05616966387, 7146.571205727673, 7348.1182258129365, 7412.1846596875375, 7413.258674132544, 7421.257874212535, 7382.483226174117, 7470.2903224193615, 7473.215312392338, 7530.736424225126, 7550.735724249625], "value": 6563.695790124309, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.3663291378822, 522.9869253268669, 523.8396950754079, 526.4367127173924, 533.0352062766393, 6271.839709653717, 6305.243370795511, 6369.6496692681885, 6459.677016149191, 6482.254540727818, 6285.402886725765, 6329.341676495871, 6334.778282760104, 6352.714127864246, 6365.649889256089, 5222.294990176335, 5225.843224703265, 5250.789968401259, 5256.106461901476, 5285.8942821143555, 4916.410030796309, 5028.943921776431, 5197.480251974791, 5215.426303106674, 5298.973505132488, 4970.850874473754, 4977.676451030689, 5046.142155833499, 5046.949530504703, 5049.293098966147, 4986.127427700138, 5022.6233032522705, 5022.773975171108, 5038.345015148044, 5058.924116138261, 4491.326301054835, 4516.390287311204, 4521.457425108975, 4524.502304746476, 4543.863684089482, 6236.594621349598, 6245.281792593864, 6254.906176407358, 6271.874562508724, 6333.46165575922, 5730.4269573042775, 5731.570132240098, 5733.312002559678, 5754.424557544254, 5791.681457519835, 6492.220933488012, 6533.803985880431, 6554.344565543455, 6577.638229897354, 6632.336766323329, 4877.561019508261, 4896.363472748555, 4938.777755001033, 4957.405111386656, 4971.428285747149, 6580.835479113012, 6709.6980635871505, 6862.495189255886, 6961.686724097428, 7075.858482830315, 4830.66185367028, 4958.429780575208, 4967.850964471072, 5014.122528557488, 5030.591434398374, 5020.799168033311, 5079.263506791494, 5119.8720031998755, 5120.462351453119, 5121.564667003272, 5618.129189975561, 5639.407862174413, 5672.517835983987, 5701.344345400249, 5728.903833758883, 6515.481639914393, 6633.402993730588, 6637.3030831762935, 6687.732490700318, 6703.581173601498, 1758.5867321179512, 1758.78015248094, 1759.542518945058, 1770.2918832466858, 1778.7331900215072, 1178.7406770510474, 1216.975660486803, 1225.5587988324373, 1231.8952889004356, 1249.731307768834, 5706.800261990894, 5714.856828744174, 5715.342735585377, 5726.856828579235, 5784.587093614788, 6731.461483081342, 6765.241037330231, 6767.221769496571, 6767.864642707217, 6875.278095799969, 5675.8080803031, 5678.176664383641, 5723.7996670116345, 5747.655140691572, 5752.33848107473, 3502.387082260594, 3552.182997910443, 3565.037439891215, 3613.259281847215, 3632.854685812538, 6573.704183311763, 6598.0, 6609.834754131185, 6610.272869984298, 6658.734840380313, 6518.869622607522, 6867.690953907087, 6869.519133660696, 6942.444604431634, 6942.535399933052, 6392.456641185551, 6417.582857114273, 6501.284858665448, 6502.219733631994, 6540.149780528501, 6614.570052946543, 6674.566153200027, 6729.0, 6737.629430381376, 6770.5599136056, 6474.0, 6482.189726284215, 6695.497837662292, 6710.563813352116, 6763.323667633197, 6432.9678351607945, 6468.482521398183, 6479.157709497735, 6493.383128602775, 6523.804285871432, 4958.82644107469, 4992.450830408579, 4999.950000499948, 5017.473165317735, 5029.270755740324, 6432.388923052303, 6457.967710161419, 6518.837029074311, 6697.263301036979, 6800.761973330909, 6386.584871983307, 6429.7106630200815, 6489.9351006491215, 6497.855707616381, 6542.34576542342, 6365.2361716594305, 6460.353964603502, 6486.54594178403, 6525.804225873231, 6549.515979180672, 6350.90473642905, 6440.259370172303, 6445.677716114269, 6474.805755827333, 6524.869502610016, 6562.146920900253, 6579.506537009648, 6614.966925165343, 6625.238097618835, 6712.6979285931275, 6426.389492998158, 6430.5177111715875, 6438.549301548846, 6453.741850326122, 6461.450776684034], "value": 5084.386644118716, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.6401439424234, 910.4400793511991, 914.8444764390053, 919.6137622198673, 921.2675922641499, 19316.613667726655, 19750.209991600317, 19981.300654477098, 19988.999999999964, 19996.90001549992, 18602.627947441077, 18799.248030078816, 18825.05874706266, 18828.152733127008, 18869.433916982478, 956.1394744729729, 966.3911735606558, 967.4050458967755, 968.5980318167951, 968.6851773173712, 987.1757082835841, 988.5255077562774, 988.9159421449185, 990.0594435286471, 991.3704797453597, 992.4343124419084, 1024.9180065594771, 1025.354026963016, 1026.5893642542967, 1028.8456731490262, 1014.6702321745462, 1021.0, 1021.2136654775818, 1021.7547788530774, 1022.6522982186056, 704.8308405982527, 736.054170391045, 743.6207534157619, 747.667288056815, 748.9026426564566, 19533.121009554605, 19692.310769123156, 19953.401397958085, 19968.600627987358, 19985.800141998538, 20211.595768084557, 20326.695099573517, 21194.470138246514, 21298.467538311666, 21298.893505532375, 17214.31142754288, 17538.561535961577, 17709.203085861038, 17714.645707085787, 17864.91067544667, 3641.6904563112166, 3670.5595328560476, 3673.3571624965793, 3674.1733110050027, 3675.4854320395166, 105202.60578532172, 121859.0, 124606.0, 124803.37598312041, 124922.37538812248, 16672.166391680366, 16862.57843553921, 17006.57483562921, 17201.913990429966, 17295.56761080983, 30547.694523054484, 30594.847025764728, 31033.379332413224, 31501.36997260042, 31945.840270798497, 8744.606492707844, 8771.736847894574, 8780.165884240987, 8834.116588341243, 8869.600867960957, 4136.9793151034055, 4244.766537840448, 4260.318349064197, 4348.760818154969, 4355.020120472932, 5301.628885977944, 5392.325959255093, 5392.487713667274, 5404.702741349263, 5407.405185429599, 1020.6886899495726, 1050.2648146297622, 1058.7353161709573, 1065.728239298974, 1066.3069005146563, 684.7192651013061, 697.7732237022988, 701.7298340139083, 702.5679207287482, 707.7982774909126, 12062.698432539257, 12274.570390036306, 12543.686407839878, 12640.367981601066, 12933.870661293455, 677.3260605697362, 681.57401623985, 687.5324779150226, 699.9615021173885, 708.3766285668579, 5735.827925162252, 5777.133429985452, 5801.013827649225, 5806.912896306559, 5809.0, 119031.0, 120072.0, 120408.0, 123054.38472807758, 125922.3703881493, 111013.0, 111646.44176779065, 111906.0, 113244.34497642022, 117696.41151794186, 93234.60148097784, 93829.0, 94083.52958235165, 94392.99999999863, 95751.52124239334, 10512.579496820348, 10597.89402105969, 10673.8932610676, 10768.3538987661, 10919.781604368027, 43496.78251608848, 43854.0, 44125.0, 44646.77676611596, 44970.550294497945, 56049.43950560442, 56261.0, 56975.715121424124, 59461.0, 59835.700821495615, 15230.238488075773, 15587.376504939675, 15631.76552351693, 15702.9214853925, 15741.842581574037, 16945.0, 17081.743773843154, 17139.91430042842, 17306.394276200273, 17336.219870106128, 14016.859831401556, 14037.789433158716, 14140.222287774272, 14219.360128794437, 14267.143971361751, 15320.923395382952, 15345.769813452584, 15447.0, 15565.610859728597, 15572.610684732972, 14764.63088422798, 14978.92510537484, 15143.848561514244, 15183.240837957837, 15243.0, 10760.300580462557, 10775.299605525615, 11125.276857004268, 11165.609203677832, 11190.272632278877, 10573.418461984666, 10615.946920265349, 10645.946770266099, 10781.514831832743, 10789.082927951213], "value": 8835.18431061573, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1413.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 106.0, 107.0, 106.0, 107.0, 110.0, 107.0, 107.0, 107.0, 106.0], "value": 107.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 116.0, 115.0, 119.0, 117.0, 116.0, 145.0, 119.0, 116.0], "value": 119.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 160.0, 162.0, 161.0, 162.0, 162.0, 166.0, 167.0, 161.0], "value": 162.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 72.0, 74.0, 71.0, 74.0, 74.0, 73.0, 75.0, 76.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 86.0, 78.0, 79.0, 80.0, 80.0, 84.0, 77.0, 82.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 111.0, 113.0, 110.0, 115.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 148.0, 147.0, 148.0, 149.0, 149.0, 149.0, 148.0, 147.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 50.0, 52.0, 52.0, 52.0, 53.0, 53.0, 54.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 72.0, 74.0, 75.0, 75.0, 76.0, 76.0, 73.0, 72.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 117.0, 116.0, 116.0, 119.0, 112.0, 108.0, 114.0, 118.0], "value": 114.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 171.0, 166.0, 166.0, 167.0, 176.0, 165.0, 174.0, 172.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 64.0, 66.0, 67.0, 65.0, 65.0, 69.0, 66.0, 64.0], "value": 65.7, "unit": "score"}]}]} 15:44:23 INFO - Return code: 0 15:44:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:44:23 INFO - # TBPL SUCCESS # 15:44:23 INFO - Running post-action listener: _package_coverage_data 15:44:23 INFO - Running post-action listener: _resource_record_post_action 15:44:23 INFO - [mozharness: 2017-09-13 22:44:23.665726Z] Finished run-tests step (success) 15:44:23 INFO - Running post-run listener: _resource_record_post_run 15:44:23 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 337530880; Read time: 8; Write time: 350596 15:44:23 INFO - TinderboxPrint: CPU usage
13.1% 15:44:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 15:44:23 INFO - TinderboxPrint: I/O write bytes / time
337,530,880 / 350,596 15:44:23 INFO - TinderboxPrint: CPU idle
8,250.0 (86.9%) 15:44:23 INFO - TinderboxPrint: CPU user
1,193.8 (12.6%) 15:44:23 INFO - TinderboxPrint: Swap in / out
0 / 0 15:44:23 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12939264; Read time: 0; Write time: 1912 15:44:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:44:23 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 323178496; Read time: 0; Write time: 348492 15:44:24 INFO - Running post-run listener: _upload_blobber_files 15:44:24 INFO - Blob upload gear active. 15:44:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:44:24 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 15:44:24 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:44:24 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:44:24 INFO - (blobuploader) - INFO - Open directory for files ... 15:44:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:44:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:44:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:25 INFO - (blobuploader) - INFO - Done attempting. 15:44:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:44:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:44:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:26 INFO - (blobuploader) - INFO - Done attempting. 15:44:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:44:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:44:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:33 INFO - (blobuploader) - INFO - Done attempting. 15:44:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:44:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:44:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:34 INFO - (blobuploader) - INFO - Done attempting. 15:44:34 INFO - (blobuploader) - INFO - Iteration through files over. 15:44:34 INFO - Return code: 0 15:44:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:44:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:44:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/acbcfabfba935ad395c3dfadc907fadc2febd38cb1129523abede9fe60418246e0187797c29903843056ae320bfc9737095dd36fc1b16202ec7e005f86add2c1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bf9654426e5e27fde6c04702d9674f7dd480474c9adea2750285de9ad2637a8aaa73b78c9e7eba488ee8ba8dd2e0834b7b9b54c4c370506e9d8744ca9ec1db0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c57989158d870316566c0d59f51dca5be6a5989c3f116536488eb6e4a180dd2540fdfc92aa4fa401491bf35adc61183ad3bb77a70b8aea65ada8cfbe3ea30b55", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37e88dbd61272e97a15cc1d27ef7993f98c6c44850db410057393253cfe342bea23cea162e84d084b458426293ec01309aa0bcaf82febe1a2528c2144d30f543"} 15:44:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:44:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:44:34 INFO - Contents: 15:44:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/acbcfabfba935ad395c3dfadc907fadc2febd38cb1129523abede9fe60418246e0187797c29903843056ae320bfc9737095dd36fc1b16202ec7e005f86add2c1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bf9654426e5e27fde6c04702d9674f7dd480474c9adea2750285de9ad2637a8aaa73b78c9e7eba488ee8ba8dd2e0834b7b9b54c4c370506e9d8744ca9ec1db0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c57989158d870316566c0d59f51dca5be6a5989c3f116536488eb6e4a180dd2540fdfc92aa4fa401491bf35adc61183ad3bb77a70b8aea65ada8cfbe3ea30b55", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37e88dbd61272e97a15cc1d27ef7993f98c6c44850db410057393253cfe342bea23cea162e84d084b458426293ec01309aa0bcaf82febe1a2528c2144d30f543"} 15:44:34 INFO - Running post-run listener: copy_logs_to_upload_dir 15:44:34 INFO - Copying logs to upload dir... 15:44:34 INFO - mkdir: /builds/slave/test/build/upload/logs 15:44:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.937339 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-13 15:44:34.767685) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 15:44:34.774782) ========= 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=62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/acbcfabfba935ad395c3dfadc907fadc2febd38cb1129523abede9fe60418246e0187797c29903843056ae320bfc9737095dd36fc1b16202ec7e005f86add2c1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bf9654426e5e27fde6c04702d9674f7dd480474c9adea2750285de9ad2637a8aaa73b78c9e7eba488ee8ba8dd2e0834b7b9b54c4c370506e9d8744ca9ec1db0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c57989158d870316566c0d59f51dca5be6a5989c3f116536488eb6e4a180dd2540fdfc92aa4fa401491bf35adc61183ad3bb77a70b8aea65ada8cfbe3ea30b55", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37e88dbd61272e97a15cc1d27ef7993f98c6c44850db410057393253cfe342bea23cea162e84d084b458426293ec01309aa0bcaf82febe1a2528c2144d30f543"} build_url:https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011270 build_url: 'https://queue.taskcluster.net/v1/task/LVYn6kl1Sru36vqr-ftIgg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/acbcfabfba935ad395c3dfadc907fadc2febd38cb1129523abede9fe60418246e0187797c29903843056ae320bfc9737095dd36fc1b16202ec7e005f86add2c1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8bf9654426e5e27fde6c04702d9674f7dd480474c9adea2750285de9ad2637a8aaa73b78c9e7eba488ee8ba8dd2e0834b7b9b54c4c370506e9d8744ca9ec1db0", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c57989158d870316566c0d59f51dca5be6a5989c3f116536488eb6e4a180dd2540fdfc92aa4fa401491bf35adc61183ad3bb77a70b8aea65ada8cfbe3ea30b55", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/37e88dbd61272e97a15cc1d27ef7993f98c6c44850db410057393253cfe342bea23cea162e84d084b458426293ec01309aa0bcaf82febe1a2528c2144d30f543"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 15:44:34.806238) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:44:34.806764) ========= 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=62c568580acbfdaac3eec7be000001dc-1505341428.371456-1096295056 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004725 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 15:44:34.857822) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 15:44:34.858147) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 15:44:34.858474) ========= ========= Total master_lag: 0.21 =========