builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-051 starttime: 1505367925.18 results: success (0) revision: 6f08f91f8db00d385e5d564b0102bf0dece39f6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:25.176901) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:25.177394) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:25.177661) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:25.216304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:25.216736) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 22:45:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 47.0M=0s 2017-09-13 22:45:25 (47.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129094 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:25.401488) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:25.404953) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022859 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:25.456221) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-13 22:45:25.456557) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6f08f91f8db00d385e5d564b0102bf0dece39f6f --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 6f08f91f8db00d385e5d564b0102bf0dece39f6f --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=5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 22:45:25,526 truncating revision to first 12 chars 2017-09-13 22:45:25,526 Setting DEBUG logging. 2017-09-13 22:45:25,526 attempt 1/10 2017-09-13 22:45:25,526 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6f08f91f8db0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 22:45:26,313 attempt 1/10 2017-09-13 22:45:27,028 current task status: no status available at this point. state: STARTED 2017-09-13 22:45:27,028 sleeping for 10.00s (attempt 1/10) 2017-09-13 22:45:37,039 attempt 2/10 2017-09-13 22:45:37,464 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-13 22:45:37,639 unpacking tar archive at: mozilla-central-6f08f91f8db0/testing/mozharness/ program finished with exit code 0 elapsedTime=12.422892 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-13 22:45:37.913186) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:37.913508) ========= script_repo_revision: 6f08f91f8db00d385e5d564b0102bf0dece39f6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:37.913903) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:37.914163) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:37.927643) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-13 22:45:37.928019) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:45:38 INFO - MultiFileLogger online at 20170913 22:45:38 in /builds/slave/test 22:45:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:45:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:45:38 INFO - {'append_to_log': False, 22:45:38 INFO - 'base_work_dir': '/builds/slave/test', 22:45:38 INFO - 'blob_upload_branch': 'Firefox', 22:45:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:45:38 INFO - 'branch': 'Firefox', 22:45:38 INFO - 'buildbot_json_path': 'buildprops.json', 22:45:38 INFO - 'code_coverage': False, 22:45:38 INFO - 'config_files': ('talos/linux_config.py',), 22:45:38 INFO - 'default_actions': ('clobber', 22:45:38 INFO - 'read-buildbot-config', 22:45:38 INFO - 'download-and-extract', 22:45:38 INFO - 'populate-webroot', 22:45:38 INFO - 'create-virtualenv', 22:45:38 INFO - 'install', 22:45:38 INFO - 'setup-mitmproxy', 22:45:38 INFO - 'run-tests'), 22:45:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:45:38 INFO - 'disable_ccov_upload': False, 22:45:38 INFO - 'disable_stylo': False, 22:45:38 INFO - 'download_minidump_stackwalk': True, 22:45:38 INFO - 'download_symbols': 'ondemand', 22:45:38 INFO - 'enable_stylo': False, 22:45:38 INFO - 'enable_webrender': False, 22:45:38 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 22:45:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:45:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:45:38 INFO - 'gecko_profile': False, 22:45:38 INFO - 'gecko_profile_interval': 0, 22:45:38 INFO - 'installer_path': 'installer.exe', 22:45:38 INFO - 'log_level': 'info', 22:45:38 INFO - 'log_name': 'talos', 22:45:38 INFO - 'log_to_console': True, 22:45:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:45:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:45:38 INFO - 'opt_config_files': (), 22:45:38 INFO - 'pip_index': False, 22:45:38 INFO - 'suite': 'dromaeojs-e10s', 22:45:38 INFO - 'system_bits': '32', 22:45:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:45:38 INFO - 'title': 'talos-linux64-ix-051', 22:45:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:45:38 INFO - 'use_talos_json': True, 22:45:38 INFO - 'verify': 'False', 22:45:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:45:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:45:38 INFO - 'work_dir': 'build'} 22:45:38 INFO - [mozharness: 2017-09-14 05:45:38.127398Z] Running clobber step. 22:45:38 INFO - Running pre-action listener: _resource_record_pre_action 22:45:38 INFO - Running main action method: clobber 22:45:38 INFO - rmtree: /builds/slave/test/build 22:45:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:45:39 INFO - Running post-action listener: _resource_record_post_action 22:45:39 INFO - [mozharness: 2017-09-14 05:45:39.653313Z] Finished clobber step (success) 22:45:39 INFO - [mozharness: 2017-09-14 05:45:39.653412Z] Running read-buildbot-config step. 22:45:39 INFO - Running pre-action listener: _resource_record_pre_action 22:45:39 INFO - Running main action method: read_buildbot_config 22:45:39 INFO - Using buildbot properties: 22:45:39 INFO - { 22:45:39 INFO - "project": "", 22:45:39 INFO - "product": "firefox", 22:45:39 INFO - "who": "jdolske@mozilla.com", 22:45:39 INFO - "installer_path": "public/build/target.tar.bz2", 22:45:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 22:45:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 22:45:39 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 22:45:39 INFO - "stage_platform": "linux64-qr", 22:45:39 INFO - "basedir": "/builds/slave/test", 22:45:39 INFO - "buildnumber": 51, 22:45:39 INFO - "platform": "ubuntu64_hw_qr", 22:45:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:45:39 INFO - "slavebuilddir": "test", 22:45:39 INFO - "taskId": "Oc3SmsGNR5GOTcKxQ0AC0Q", 22:45:39 INFO - "branch": "mozilla-central", 22:45:39 INFO - "script_repo_revision": "production", 22:45:39 INFO - "revision": "6f08f91f8db00d385e5d564b0102bf0dece39f6f", 22:45:39 INFO - "slavename": "talos-linux64-ix-051", 22:45:39 INFO - "repo_path": "mozilla-central" 22:45:39 INFO - } 22:45:39 INFO - Finding installer, test and symbols from parent task. 22:45:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oc3SmsGNR5GOTcKxQ0AC0Q'}, attempt #1 22:45:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oc3SmsGNR5GOTcKxQ0AC0Q'}, attempt #1 22:45:40 INFO - Task dependencies: XkYgQGM7RR6WsMLSikInwA 22:45:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA'}, attempt #1 22:45:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2 22:45:41 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.test_packages.json 22:45:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.crashreporter-symbols.zip 22:45:41 INFO - Running post-action listener: _resource_record_post_action 22:45:41 INFO - [mozharness: 2017-09-14 05:45:41.550577Z] Finished read-buildbot-config step (success) 22:45:41 INFO - [mozharness: 2017-09-14 05:45:41.550791Z] Running download-and-extract step. 22:45:41 INFO - Running pre-action listener: _resource_record_pre_action 22:45:41 INFO - Running main action method: download_and_extract 22:45:41 INFO - mkdir: /builds/slave/test/build/tests 22:45:41 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')]} 22:45:41 INFO - trying https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.test_packages.json 22:45:41 INFO - Downloading https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 22:45:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 22:45:42 INFO - Downloaded 989 bytes. 22:45:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 22:45:42 INFO - Using the following test package requirements: 22:45:42 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 22:45:42 INFO - u'common': [u'target.common.tests.zip'], 22:45:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 22:45:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 22:45:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 22:45:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 22:45:42 INFO - u'mozbase': [u'target.common.tests.zip'], 22:45:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 22:45:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 22:45:42 INFO - u'web-platform': [u'target.common.tests.zip', 22:45:42 INFO - u'target.web-platform.tests.tar.gz'], 22:45:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 22:45:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 22:45:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.common.tests.zip 22:45:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.common.tests.zip'}, attempt #1 22:45:42 INFO - Fetch https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.common.tests.zip into memory 22:45:43 INFO - Content-Length response header: 54572630 22:45:43 INFO - Bytes received: 54572630 22:45:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.talos.tests.zip 22:45:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 22:45:54 INFO - Fetch https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.talos.tests.zip into memory 22:45:55 INFO - Content-Length response header: 14500356 22:45:55 INFO - Bytes received: 14500356 22:45:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:56 INFO - trying https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2 22:45:56 INFO - Downloading https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 22:45:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 22:45:57 INFO - Downloaded 61280115 bytes. 22:45:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2 22:45:57 INFO - mkdir: /builds/slave/test/properties 22:45:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:45:57 INFO - Writing to file /builds/slave/test/properties/build_url 22:45:57 INFO - Contents: 22:45:57 INFO - build_url:https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2 22:45:57 INFO - Running post-action listener: _resource_record_post_action 22:45:57 INFO - Running post-action listener: find_tests_for_verification 22:45:57 INFO - Running post-action listener: set_extra_try_arguments 22:45:57 INFO - [mozharness: 2017-09-14 05:45:57.388196Z] Finished download-and-extract step (success) 22:45:57 INFO - [mozharness: 2017-09-14 05:45:57.388305Z] Running populate-webroot step. 22:45:57 INFO - Running pre-action listener: _resource_record_pre_action 22:45:57 INFO - Running main action method: populate_webroot 22:45:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:45:57 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['tresize', 22:45:57 INFO - 'tcanvasmark']}, 22:45:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:45:57 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['dromaeo_css', 22:45:57 INFO - 'kraken']}, 22:45:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:45:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:45:57 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:45:57 INFO - 'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:45:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:45:57 INFO - 'tests': ['damp', 'tps']}, 22:45:57 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:45:57 INFO - 'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['damp', 'tps']}, 22:45:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:45:57 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['dromaeo_dom']}, 22:45:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:45:57 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['basic_compositor_video', 22:45:57 INFO - 'glvideo']}, 22:45:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 22:45:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 22:45:57 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:45:57 INFO - 'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['ts_paint_webext', 22:45:57 INFO - 'tp5o_webext']}, 22:45:57 INFO - 'other-e10s': {'tests': ['a11yr', 22:45:57 INFO - 'ts_paint', 22:45:57 INFO - 'tpaint', 22:45:57 INFO - 'sessionrestore', 22:45:57 INFO - 'sessionrestore_many_windows', 22:45:57 INFO - 'sessionrestore_no_auto_restore', 22:45:57 INFO - 'tabpaint']}, 22:45:57 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['a11yr', 22:45:57 INFO - 'ts_paint', 22:45:57 INFO - 'tpaint', 22:45:57 INFO - 'sessionrestore', 22:45:57 INFO - 'sessionrestore_many_windows', 22:45:57 INFO - 'sessionrestore_no_auto_restore', 22:45:57 INFO - 'tabpaint']}, 22:45:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 22:45:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 22:45:57 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['bloom_basic_singleton']}, 22:45:57 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['bloom_basic']}, 22:45:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:45:57 INFO - 'tsvgr_opacity', 22:45:57 INFO - 'tart', 22:45:57 INFO - 'tscrollx', 22:45:57 INFO - 'tsvg_static']}, 22:45:57 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['tsvgx', 22:45:57 INFO - 'tsvgr_opacity', 22:45:57 INFO - 'tart', 22:45:57 INFO - 'tscrollx', 22:45:57 INFO - 'tsvg_static']}, 22:45:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:45:57 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:45:57 INFO - 'talos_options': ['--disable-stylo'], 22:45:57 INFO - 'tests': ['tp5o']}, 22:45:57 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 22:45:57 INFO - 'talos_options': ['--mitmproxy', 22:45:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 22:45:57 INFO - '--firstNonBlankPaint'], 22:45:57 INFO - 'tests': ['tp6_google', 22:45:57 INFO - 'tp6_youtube', 22:45:57 INFO - 'tp6_amazon', 22:45:57 INFO - 'tp6_facebook']}, 22:45:57 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 22:45:57 INFO - 'talos_options': ['--disable-stylo', 22:45:57 INFO - '--mitmproxy', 22:45:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 22:45:57 INFO - '--firstNonBlankPaint'], 22:45:57 INFO - 'tests': ['tp6_google', 22:45:57 INFO - 'tp6_youtube', 22:45:57 INFO - 'tp6_amazon', 22:45:57 INFO - 'tp6_facebook']}, 22:45:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 22:45:57 INFO - 'talos_options': ['--stylo-threads=1', 22:45:57 INFO - '--mitmproxy', 22:45:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 22:45:57 INFO - '--firstNonBlankPaint'], 22:45:57 INFO - 'tests': ['tp6_google', 22:45:57 INFO - 'tp6_youtube', 22:45:57 INFO - 'tp6_amazon', 22:45:57 INFO - 'tp6_facebook']}, 22:45:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:45:57 INFO - 'talos_options': ['--xperf_path', 22:45:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:57 INFO - 'tests': ['tp5n']}, 22:45:57 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:45:57 INFO - 'talos_options': ['--disable-stylo', 22:45:57 INFO - '--xperf_path', 22:45:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:45:57 INFO - 'tests': ['tp5n']}}} 22:45:57 INFO - Running post-action listener: _resource_record_post_action 22:45:57 INFO - [mozharness: 2017-09-14 05:45:57.818563Z] Finished populate-webroot step (success) 22:45:57 INFO - [mozharness: 2017-09-14 05:45:57.818662Z] Running create-virtualenv step. 22:45:57 INFO - Running pre-action listener: _resource_record_pre_action 22:45:57 INFO - Running main action method: create_virtualenv 22:45:57 INFO - Creating virtualenv /builds/slave/test/build/venv 22:45:57 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:45:57 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 22:45:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 22:45:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:45:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:45:58 INFO - Installing distribute.............................................................................................................................................................................................done. 22:45:58 INFO - Installing pip...............done. 22:45:58 INFO - Return code: 0 22:45:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:45:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:45:58 INFO - Reading from file tmpfile_stdout 22:45:58 INFO - Output received: 22:45:58 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 22:45:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:58 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 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:45:58 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 22:45:58 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 22:45:58 INFO - Using env: {'DISPLAY': ':0', 22:45:58 INFO - 'HOME': '/home/cltbld', 22:45:58 INFO - 'LANG': 'en_US.UTF-8', 22:45:58 INFO - 'LANGUAGE': 'en_US:en', 22:45:58 INFO - 'LOGNAME': 'cltbld', 22:45:58 INFO - 'MAIL': '/var/mail/cltbld', 22:45:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:58 INFO - 'MOZ_NO_REMOTE': '1', 22:45:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:45:58 INFO - 'NO_EM_RESTART': '1', 22:45:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:45:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:58 INFO - 'PWD': '/builds/slave/test', 22:45:58 INFO - 'SHELL': '/bin/bash', 22:45:58 INFO - 'SHLVL': '1', 22:45:58 INFO - 'TERM': 'linux', 22:45:58 INFO - 'TMOUT': '86400', 22:45:58 INFO - 'USER': 'cltbld', 22:45:58 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:45:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:58 INFO - '_': '/tools/buildbot/bin/python'} 22:46:00 INFO - Ignoring indexes: http://pypi.python.org/simple/ 22:46:00 INFO - Downloading/unpacking pip>=1.5 22:46:00 INFO - Running setup.py egg_info for package pip 22:46:00 INFO - warning: no files found matching 'pip/cacert.pem' 22:46:00 INFO - warning: no files found matching '*.html' under directory 'docs' 22:46:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 22:46:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 22:46:00 INFO - Installing collected packages: pip 22:46:00 INFO - Found existing installation: pip 1.1 22:46:00 INFO - Uninstalling pip: 22:46:00 INFO - Successfully uninstalled pip 22:46:00 INFO - Running setup.py install for pip 22:46:00 INFO - warning: no files found matching 'pip/cacert.pem' 22:46:00 INFO - warning: no files found matching '*.html' under directory 'docs' 22:46:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 22:46:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 22:46:00 INFO - Installing pip script to /builds/slave/test/build/venv/bin 22:46:00 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 22:46:00 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 22:46:00 INFO - Successfully installed pip 22:46:00 INFO - Cleaning up... 22:46:00 INFO - Return code: 0 22:46:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:00 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 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:46:00 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 22:46:00 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 22:46:00 INFO - Using env: {'DISPLAY': ':0', 22:46:00 INFO - 'HOME': '/home/cltbld', 22:46:00 INFO - 'LANG': 'en_US.UTF-8', 22:46:00 INFO - 'LANGUAGE': 'en_US:en', 22:46:00 INFO - 'LOGNAME': 'cltbld', 22:46:00 INFO - 'MAIL': '/var/mail/cltbld', 22:46:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:00 INFO - 'MOZ_NO_REMOTE': '1', 22:46:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:00 INFO - 'NO_EM_RESTART': '1', 22:46:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:00 INFO - 'PWD': '/builds/slave/test', 22:46:00 INFO - 'SHELL': '/bin/bash', 22:46:00 INFO - 'SHLVL': '1', 22:46:00 INFO - 'TERM': 'linux', 22:46:00 INFO - 'TMOUT': '86400', 22:46:00 INFO - 'USER': 'cltbld', 22:46:00 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:00 INFO - '_': '/tools/buildbot/bin/python'} 22:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:00 INFO - Downloading/unpacking psutil>=3.1.1 22:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:46:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:46:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:46:01 INFO - Installing collected packages: psutil 22:46:01 INFO - Running setup.py install for psutil 22:46:01 INFO - building 'psutil._psutil_linux' extension 22:46:01 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 22:46:01 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 22:46:01 INFO - building 'psutil._psutil_posix' extension 22:46:01 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 22:46:01 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 22:46:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:46:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:46:01 INFO - Successfully installed psutil 22:46:01 INFO - Cleaning up... 22:46:01 INFO - Return code: 0 22:46:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:46:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:01 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 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:46:01 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 22:46:01 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 22:46:01 INFO - Using env: {'DISPLAY': ':0', 22:46:01 INFO - 'HOME': '/home/cltbld', 22:46:01 INFO - 'LANG': 'en_US.UTF-8', 22:46:01 INFO - 'LANGUAGE': 'en_US:en', 22:46:01 INFO - 'LOGNAME': 'cltbld', 22:46:01 INFO - 'MAIL': '/var/mail/cltbld', 22:46:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:01 INFO - 'MOZ_NO_REMOTE': '1', 22:46:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:01 INFO - 'NO_EM_RESTART': '1', 22:46:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:01 INFO - 'PWD': '/builds/slave/test', 22:46:01 INFO - 'SHELL': '/bin/bash', 22:46:01 INFO - 'SHLVL': '1', 22:46:01 INFO - 'TERM': 'linux', 22:46:01 INFO - 'TMOUT': '86400', 22:46:01 INFO - 'USER': 'cltbld', 22:46:01 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:01 INFO - '_': '/tools/buildbot/bin/python'} 22:46:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:46:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:46:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:46:03 INFO - Installing collected packages: mozsystemmonitor 22:46:03 INFO - Running setup.py install for mozsystemmonitor 22:46:03 INFO - Successfully installed mozsystemmonitor 22:46:03 INFO - Cleaning up... 22:46:04 INFO - Return code: 0 22:46:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:46:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:46:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:46:04 INFO - Using env: {'DISPLAY': ':0', 22:46:04 INFO - 'HOME': '/home/cltbld', 22:46:04 INFO - 'LANG': 'en_US.UTF-8', 22:46:04 INFO - 'LANGUAGE': 'en_US:en', 22:46:04 INFO - 'LOGNAME': 'cltbld', 22:46:04 INFO - 'MAIL': '/var/mail/cltbld', 22:46:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:04 INFO - 'MOZ_NO_REMOTE': '1', 22:46:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:04 INFO - 'NO_EM_RESTART': '1', 22:46:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:04 INFO - 'PWD': '/builds/slave/test', 22:46:04 INFO - 'SHELL': '/bin/bash', 22:46:04 INFO - 'SHLVL': '1', 22:46:04 INFO - 'TERM': 'linux', 22:46:04 INFO - 'TMOUT': '86400', 22:46:04 INFO - 'USER': 'cltbld', 22:46:04 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:04 INFO - '_': '/tools/buildbot/bin/python'} 22:46:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:05 INFO - Downloading/unpacking jsonschema==2.5.1 22:46:05 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 22:46:05 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 22:46:05 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 22:46:05 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 22:46:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:46:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:46:06 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 22:46:06 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 22:46:06 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 22:46:06 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 22:46:07 INFO - Downloading functools32-3.2.3-2.tar.gz 22:46:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:46:07 INFO - warning: no files found matching '*.txt' 22:46:07 INFO - no previously-included directories found matching 'build' 22:46:07 INFO - no previously-included directories found matching 'dist' 22:46:07 INFO - no previously-included directories found matching '.git*' 22:46:07 INFO - Installing collected packages: jsonschema, functools32 22:46:07 INFO - Running setup.py install for functools32 22:46:07 INFO - warning: no files found matching '*.txt' 22:46:07 INFO - no previously-included directories found matching 'build' 22:46:07 INFO - no previously-included directories found matching 'dist' 22:46:07 INFO - no previously-included directories found matching '.git*' 22:46:07 INFO - Successfully installed jsonschema functools32 22:46:07 INFO - Cleaning up... 22:46:07 INFO - Return code: 0 22:46:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:46:07 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')]} 22:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:07 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')]} 22:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:07 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 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:46:07 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 22:46:07 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 22:46:07 INFO - Using env: {'DISPLAY': ':0', 22:46:07 INFO - 'HOME': '/home/cltbld', 22:46:07 INFO - 'LANG': 'en_US.UTF-8', 22:46:07 INFO - 'LANGUAGE': 'en_US:en', 22:46:07 INFO - 'LOGNAME': 'cltbld', 22:46:07 INFO - 'MAIL': '/var/mail/cltbld', 22:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:07 INFO - 'MOZ_NO_REMOTE': '1', 22:46:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:07 INFO - 'NO_EM_RESTART': '1', 22:46:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:07 INFO - 'PWD': '/builds/slave/test', 22:46:07 INFO - 'SHELL': '/bin/bash', 22:46:07 INFO - 'SHLVL': '1', 22:46:07 INFO - 'TERM': 'linux', 22:46:07 INFO - 'TMOUT': '86400', 22:46:07 INFO - 'USER': 'cltbld', 22:46:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:07 INFO - '_': '/tools/buildbot/bin/python'} 22:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:46:07 INFO - Cleaning up... 22:46:07 INFO - Return code: 0 22:46:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:46:07 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')]} 22:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:07 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')]} 22:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:07 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 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:46:07 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 22:46:07 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 22:46:07 INFO - Using env: {'DISPLAY': ':0', 22:46:07 INFO - 'HOME': '/home/cltbld', 22:46:07 INFO - 'LANG': 'en_US.UTF-8', 22:46:07 INFO - 'LANGUAGE': 'en_US:en', 22:46:07 INFO - 'LOGNAME': 'cltbld', 22:46:07 INFO - 'MAIL': '/var/mail/cltbld', 22:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:07 INFO - 'MOZ_NO_REMOTE': '1', 22:46:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:07 INFO - 'NO_EM_RESTART': '1', 22:46:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:07 INFO - 'PWD': '/builds/slave/test', 22:46:07 INFO - 'SHELL': '/bin/bash', 22:46:07 INFO - 'SHLVL': '1', 22:46:07 INFO - 'TERM': 'linux', 22:46:07 INFO - 'TMOUT': '86400', 22:46:07 INFO - 'USER': 'cltbld', 22:46:07 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:07 INFO - '_': '/tools/buildbot/bin/python'} 22:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:07 INFO - Downloading/unpacking blobuploader==1.2.4 22:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:46:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:46:08 INFO - Downloading blobuploader-1.2.4.tar.gz 22:46:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:46:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:46:08 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 22:46:08 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 22:46:08 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 22:46:08 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 22:46:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:46:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:46:09 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 22:46:09 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 22:46:09 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 22:46:09 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 22:46:09 INFO - Downloading docopt-0.6.1.tar.gz 22:46:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:46:09 INFO - Installing collected packages: blobuploader, requests, docopt 22:46:09 INFO - Running setup.py install for blobuploader 22:46:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:46:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:46:09 INFO - Running setup.py install for requests 22:46:09 INFO - Running setup.py install for docopt 22:46:09 INFO - Successfully installed blobuploader requests docopt 22:46:09 INFO - Cleaning up... 22:46:09 INFO - Return code: 0 22:46:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:46:09 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')]} 22:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:09 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')]} 22:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:09 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 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:46:09 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 22:46:09 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 22:46:09 INFO - Using env: {'DISPLAY': ':0', 22:46:09 INFO - 'HOME': '/home/cltbld', 22:46:09 INFO - 'LANG': 'en_US.UTF-8', 22:46:09 INFO - 'LANGUAGE': 'en_US:en', 22:46:09 INFO - 'LOGNAME': 'cltbld', 22:46:09 INFO - 'MAIL': '/var/mail/cltbld', 22:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:09 INFO - 'MOZ_NO_REMOTE': '1', 22:46:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:09 INFO - 'NO_EM_RESTART': '1', 22:46:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:09 INFO - 'PWD': '/builds/slave/test', 22:46:09 INFO - 'SHELL': '/bin/bash', 22:46:09 INFO - 'SHLVL': '1', 22:46:09 INFO - 'TERM': 'linux', 22:46:09 INFO - 'TMOUT': '86400', 22:46:09 INFO - 'USER': 'cltbld', 22:46:09 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:09 INFO - '_': '/tools/buildbot/bin/python'} 22:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:46:10 INFO - Running setup.py (path:/tmp/pip-xlg10l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:46:10 INFO - Running setup.py (path:/tmp/pip-fjlAFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:46:10 INFO - Running setup.py (path:/tmp/pip-tt8Zf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:46:10 INFO - Running setup.py (path:/tmp/pip-yuE8nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:46:10 INFO - Running setup.py (path:/tmp/pip-OmqWiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:46:10 INFO - Running setup.py (path:/tmp/pip-J5NdiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:46:10 INFO - Running setup.py (path:/tmp/pip-nv0qFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:46:10 INFO - Running setup.py (path:/tmp/pip-SzrX_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:46:10 INFO - Running setup.py (path:/tmp/pip-ijpegc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:46:10 INFO - Running setup.py (path:/tmp/pip-JJpUx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:46:10 INFO - Running setup.py (path:/tmp/pip-N5oke5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:46:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:46:10 INFO - Running setup.py (path:/tmp/pip-B_KqnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:46:11 INFO - Running setup.py (path:/tmp/pip-UkciNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:46:11 INFO - Running setup.py (path:/tmp/pip-4z7jJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:11 INFO - Running setup.py (path:/tmp/pip-l7oo4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:46:11 INFO - Running setup.py (path:/tmp/pip-IPzv5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:46:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:46:11 INFO - Running setup.py (path:/tmp/pip-lKD82B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:46:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:46:11 INFO - Running setup.py install for manifestparser 22:46:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:46:11 INFO - Running setup.py install for mozcrash 22:46:11 INFO - Running setup.py install for mozdebug 22:46:11 INFO - Running setup.py install for mozdevice 22:46:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:46:11 INFO - Running setup.py install for mozfile 22:46:11 INFO - Running setup.py install for mozhttpd 22:46:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:46:11 INFO - Running setup.py install for mozinfo 22:46:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Running setup.py install for mozInstall 22:46:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Running setup.py install for mozleak 22:46:12 INFO - Running setup.py install for mozlog 22:46:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Running setup.py install for moznetwork 22:46:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Running setup.py install for mozprocess 22:46:12 INFO - Running setup.py install for mozprofile 22:46:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Running setup.py install for mozrunner 22:46:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:46:12 INFO - Running setup.py install for mozscreenshot 22:46:12 INFO - Running setup.py install for moztest 22:46:12 INFO - Running setup.py install for mozversion 22:46:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:46:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:46:13 INFO - Cleaning up... 22:46:13 INFO - Return code: 0 22:46:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:46:13 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')]} 22:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:13 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')]} 22:46:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:13 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 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:46:13 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 22:46:13 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 22:46:13 INFO - Using env: {'DISPLAY': ':0', 22:46:13 INFO - 'HOME': '/home/cltbld', 22:46:13 INFO - 'LANG': 'en_US.UTF-8', 22:46:13 INFO - 'LANGUAGE': 'en_US:en', 22:46:13 INFO - 'LOGNAME': 'cltbld', 22:46:13 INFO - 'MAIL': '/var/mail/cltbld', 22:46:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:13 INFO - 'MOZ_NO_REMOTE': '1', 22:46:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:13 INFO - 'NO_EM_RESTART': '1', 22:46:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:13 INFO - 'PWD': '/builds/slave/test', 22:46:13 INFO - 'SHELL': '/bin/bash', 22:46:13 INFO - 'SHLVL': '1', 22:46:13 INFO - 'TERM': 'linux', 22:46:13 INFO - 'TMOUT': '86400', 22:46:13 INFO - 'USER': 'cltbld', 22:46:13 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:13 INFO - '_': '/tools/buildbot/bin/python'} 22:46:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:46:13 INFO - Running setup.py (path:/tmp/pip-GUnKhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:46:13 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)) 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:46:13 INFO - Running setup.py (path:/tmp/pip-GincRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:46:13 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)) 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:46:13 INFO - Running setup.py (path:/tmp/pip-CVJas8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:46:13 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)) 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:46:13 INFO - Running setup.py (path:/tmp/pip-TLe4dL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:46:13 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)) 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:46:13 INFO - Running setup.py (path:/tmp/pip-GQz4hG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:46:13 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)) 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:46:13 INFO - Running setup.py (path:/tmp/pip-f9kVdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:46:13 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)) 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:46:13 INFO - Running setup.py (path:/tmp/pip-LKJfuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:46:13 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)) 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:46:13 INFO - Running setup.py (path:/tmp/pip-7JKm4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:46:13 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)) 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:46:13 INFO - Running setup.py (path:/tmp/pip-D3AiEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:46:13 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)) 22:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:46:13 INFO - Running setup.py (path:/tmp/pip-rcXH6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:46:14 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)) 22:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:46:14 INFO - Running setup.py (path:/tmp/pip-Zz2eLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:46:14 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)) 22:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:46:14 INFO - Running setup.py (path:/tmp/pip-u9iCP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:46:14 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)) 22:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:46:14 INFO - Running setup.py (path:/tmp/pip-Y3RUtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:46:14 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)) 22:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:46:14 INFO - Running setup.py (path:/tmp/pip-EOEh1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:46:14 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)) 22:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:14 INFO - Running setup.py (path:/tmp/pip-rnAQWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:46:14 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)) 22:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:46:14 INFO - Running setup.py (path:/tmp/pip-wy92R4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:46:14 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)) 22:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:46:14 INFO - Running setup.py (path:/tmp/pip-MoRgX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 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)) 22:46:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:46: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 22:46: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 22:46: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 22:46: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 22:46:15 INFO - Downloading blessings-1.6.tar.gz 22:46:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:46:15 INFO - Installing collected packages: blessings 22:46:15 INFO - Running setup.py install for blessings 22:46:15 INFO - Successfully installed blessings 22:46:15 INFO - Cleaning up... 22:46:15 INFO - Return code: 0 22:46:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:46:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:46:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:46:16 INFO - Reading from file tmpfile_stdout 22:46:16 INFO - Current package versions: 22:46:16 INFO - argparse == 1.2.1 22:46:16 INFO - blessings == 1.6 22:46:16 INFO - blobuploader == 1.2.4 22:46:16 INFO - docopt == 0.6.1 22:46:16 INFO - functools32 == 3.2.3-2 22:46:16 INFO - jsonschema == 2.5.1 22:46:16 INFO - manifestparser == 1.1 22:46:16 INFO - mozInstall == 1.13 22:46:16 INFO - mozcrash == 1.0 22:46:16 INFO - mozdebug == 0.1 22:46:16 INFO - mozdevice == 0.51 22:46:16 INFO - mozfile == 1.2 22:46:16 INFO - mozhttpd == 0.7 22:46:16 INFO - mozinfo == 0.10 22:46:16 INFO - mozleak == 0.1 22:46:16 INFO - mozlog == 3.5 22:46:16 INFO - moznetwork == 0.27 22:46:16 INFO - mozprocess == 0.25 22:46:16 INFO - mozprofile == 0.28 22:46:16 INFO - mozrunner == 6.13 22:46:16 INFO - mozscreenshot == 0.1 22:46:16 INFO - mozsystemmonitor == 0.3 22:46:16 INFO - moztest == 0.8 22:46:16 INFO - mozversion == 1.4 22:46:16 INFO - psutil == 3.1.1 22:46:16 INFO - requests == 1.2.3 22:46:16 INFO - wsgiref == 0.1.2 22:46:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:46: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')]} 22:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46: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')]} 22:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:16 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 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:46:16 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 22:46:16 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 22:46:16 INFO - Using env: {'DISPLAY': ':0', 22:46:16 INFO - 'HOME': '/home/cltbld', 22:46:16 INFO - 'LANG': 'en_US.UTF-8', 22:46:16 INFO - 'LANGUAGE': 'en_US:en', 22:46:16 INFO - 'LOGNAME': 'cltbld', 22:46:16 INFO - 'MAIL': '/var/mail/cltbld', 22:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:16 INFO - 'MOZ_NO_REMOTE': '1', 22:46:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:16 INFO - 'NO_EM_RESTART': '1', 22:46:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:16 INFO - 'PWD': '/builds/slave/test', 22:46:16 INFO - 'SHELL': '/bin/bash', 22:46:16 INFO - 'SHLVL': '1', 22:46:16 INFO - 'TERM': 'linux', 22:46:16 INFO - 'TMOUT': '86400', 22:46:16 INFO - 'USER': 'cltbld', 22:46:16 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:16 INFO - '_': '/tools/buildbot/bin/python'} 22:46:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 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)) 22:46:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 22:46:16 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 22:46:16 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 22:46:16 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 22:46:16 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 22:46:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 22:46:17 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)) 22:46:17 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)) 22:46:17 INFO - Installing collected packages: simplejson 22:46:17 INFO - Running setup.py install for simplejson 22:46:17 INFO - building 'simplejson._speedups' extension 22:46:17 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 22:46:18 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 22:46:18 INFO - Successfully installed simplejson 22:46:18 INFO - Cleaning up... 22:46:18 INFO - Return code: 0 22:46:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:46:18 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')]} 22:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:18 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')]} 22:46:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:18 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 0x1f201f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20e9a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x228ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', '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 22:46:18 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 22:46:18 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 22:46:18 INFO - Using env: {'DISPLAY': ':0', 22:46:18 INFO - 'HOME': '/home/cltbld', 22:46:18 INFO - 'LANG': 'en_US.UTF-8', 22:46:18 INFO - 'LANGUAGE': 'en_US:en', 22:46:18 INFO - 'LOGNAME': 'cltbld', 22:46:18 INFO - 'MAIL': '/var/mail/cltbld', 22:46:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:18 INFO - 'MOZ_NO_REMOTE': '1', 22:46:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:18 INFO - 'NO_EM_RESTART': '1', 22:46:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:18 INFO - 'PWD': '/builds/slave/test', 22:46:18 INFO - 'SHELL': '/bin/bash', 22:46:18 INFO - 'SHLVL': '1', 22:46:18 INFO - 'TERM': 'linux', 22:46:18 INFO - 'TMOUT': '86400', 22:46:18 INFO - 'USER': 'cltbld', 22:46:18 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:18 INFO - '_': '/tools/buildbot/bin/python'} 22:46:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:46:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:46:18 INFO - Cleaning up... 22:46:18 INFO - Return code: 0 22:46:18 INFO - Running post-action listener: _resource_record_post_action 22:46:18 INFO - Running post-action listener: _start_resource_monitoring 22:46:18 INFO - Starting resource monitoring. 22:46:18 INFO - [mozharness: 2017-09-14 05:46:18.775030Z] Finished create-virtualenv step (success) 22:46:18 INFO - [mozharness: 2017-09-14 05:46:18.775492Z] Running install step. 22:46:18 INFO - Running pre-action listener: _resource_record_pre_action 22:46:18 INFO - Running main action method: install 22:46:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:46:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:46:18 INFO - Reading from file tmpfile_stdout 22:46:18 INFO - Detecting whether we're running mozinstall >=1.0... 22:46:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:46:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:46:19 INFO - Reading from file tmpfile_stdout 22:46:19 INFO - Output received: 22:46:19 INFO - Usage: mozinstall [options] installer 22:46:19 INFO - Options: 22:46:19 INFO - -h, --help show this help message and exit 22:46:19 INFO - -d DEST, --destination=DEST 22:46:19 INFO - Directory to install application into. [default: 22:46:19 INFO - "/builds/slave/test"] 22:46:19 INFO - --app=APP Application being installed. [default: firefox] 22:46:19 INFO - mkdir: /builds/slave/test/build/application 22:46:19 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'] 22:46:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 22:46:36 INFO - Reading from file tmpfile_stdout 22:46:36 INFO - Output received: 22:46:36 INFO - /builds/slave/test/build/application/firefox/firefox 22:46:36 INFO - Running post-action listener: _resource_record_post_action 22:46:36 INFO - [mozharness: 2017-09-14 05:46:36.859049Z] Finished install step (success) 22:46:36 INFO - [mozharness: 2017-09-14 05:46:36.859304Z] Running setup-mitmproxy step. 22:46:36 INFO - Running pre-action listener: _resource_record_pre_action 22:46:36 INFO - Running main action method: setup_mitmproxy 22:46:36 INFO - Skipping: mitmproxy is not required 22:46:36 INFO - Running post-action listener: _resource_record_post_action 22:46:36 INFO - [mozharness: 2017-09-14 05:46:36.860447Z] Finished setup-mitmproxy step (success) 22:46:36 INFO - [mozharness: 2017-09-14 05:46:36.860671Z] Running run-tests step. 22:46:36 INFO - Running pre-action listener: _resource_record_pre_action 22:46:36 INFO - Running pre-action listener: _set_gcov_prefix 22:46:36 INFO - Running main action method: run_tests 22:46:36 WARNING - Try message not found. 22:46:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:46:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:46:36 INFO - Python 2.7.3 22:46:36 INFO - Return code: 0 22:46:36 INFO - grabbing minidump binary from tooltool 22:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:46:36 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 0x2290090>, '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 0x2290d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x228af90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:46:36 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 22:46:36 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 22:46:36 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 22:46:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:46:36 INFO - Return code: 0 22:46:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:46:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:46:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:46:36 INFO - ENV: RUST_BACKTRACE is now full 22:46:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:46:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:46:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 22:46:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 22:46:36 INFO - Using env: {'DISPLAY': ':0', 22:46:36 INFO - 'HOME': '/home/cltbld', 22:46:36 INFO - 'LANG': 'en_US.UTF-8', 22:46:36 INFO - 'LANGUAGE': 'en_US:en', 22:46:36 INFO - 'LOGNAME': 'cltbld', 22:46:36 INFO - 'MAIL': '/var/mail/cltbld', 22:46:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:46:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:46:36 INFO - 'MOZ_ACCELERATED': '1', 22:46:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:36 INFO - 'MOZ_NO_REMOTE': '1', 22:46:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:46:36 INFO - 'MOZ_WEBRENDER': '1', 22:46:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:36 INFO - 'NO_EM_RESTART': '1', 22:46:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:36 INFO - 'PWD': '/builds/slave/test', 22:46:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:46:36 INFO - 'RUST_BACKTRACE': 'full', 22:46:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:46:36 INFO - 'SHELL': '/bin/bash', 22:46:36 INFO - 'SHLVL': '1', 22:46:36 INFO - 'TERM': 'linux', 22:46:36 INFO - 'TMOUT': '86400', 22:46:36 INFO - 'USER': 'cltbld', 22:46:36 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:36 INFO - '_': '/tools/buildbot/bin/python'} 22:46:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 22:46:37 INFO - mozversion application_buildid: 20170914045317 22:46:37 INFO - mozversion application_changeset: 6f08f91f8db00d385e5d564b0102bf0dece39f6f 22:46:37 INFO - mozversion application_display_name: Nightly 22:46:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:46:37 INFO - mozversion application_name: Firefox 22:46:37 INFO - mozversion application_remotingname: firefox 22:46:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:46:37 INFO - mozversion application_vendor: Mozilla 22:46:37 INFO - mozversion application_version: 57.0a1 22:46:37 INFO - mozversion platform_buildid: 20170914045317 22:46:37 INFO - mozversion platform_changeset: 6f08f91f8db00d385e5d564b0102bf0dece39f6f 22:46:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:46:37 INFO - mozversion platform_version: 57.0a1 22:46:37 INFO - using testdate: 1505367997 22:46:37 INFO - actual date: 1505367997 22:46:37 INFO - starting webserver on 'localhost:33872' 22:46:37 INFO - SUITE-START | Running 2 tests 22:46:37 INFO - TEST-START | dromaeo_css 22:46:37 INFO - Initialising browser for dromaeo_css test... 22:46:37 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33872/getInfo.html -profile /tmp/tmpnJN5r0/profile 22:46:37 INFO - TEST-INFO | started process 8217 (/builds/slave/test/build/application/firefox/firefox http://localhost:33872/getInfo.html) 22:46:43 INFO - TEST-INFO | 8217: exit 0 22:46:43 INFO - Browser initialized. 22:46:43 INFO - Running cycle 1/1 for dromaeo_css test... 22:46:43 INFO - Using env: {'DISPLAY': ':0', 22:46:43 INFO - 'HOME': '/home/cltbld', 22:46:43 INFO - 'JSGC_DISABLE_POISONING': '1', 22:46:43 INFO - 'LANG': 'en_US.UTF-8', 22:46:43 INFO - 'LANGUAGE': 'en_US:en', 22:46:43 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 22:46:43 INFO - 'LOGNAME': 'cltbld', 22:46:43 INFO - 'MAIL': '/var/mail/cltbld', 22:46:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:46:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:46:43 INFO - 'MOZ_ACCELERATED': '1', 22:46:43 INFO - 'MOZ_CRASHREPORTER': '1', 22:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:43 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 22:46:43 INFO - 'MOZ_NO_REMOTE': '1', 22:46:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:46:43 INFO - 'MOZ_WEBRENDER': '1', 22:46:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:43 INFO - 'NO_EM_RESTART': '1', 22:46:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:43 INFO - 'PWD': '/builds/slave/test', 22:46:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:46:43 INFO - 'RUST_BACKTRACE': 'full', 22:46:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:46:43 INFO - 'SHELL': '/bin/bash', 22:46:43 INFO - 'SHLVL': '1', 22:46:43 INFO - 'TERM': 'linux', 22:46:43 INFO - 'TMOUT': '86400', 22:46:43 INFO - 'USER': 'cltbld', 22:46:43 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 22:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:43 INFO - '_': '/tools/buildbot/bin/python'} 22:46:43 INFO - TEST-INFO | started process 8461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnJN5r0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 22:46:44 INFO - PID 8461 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 22:46:44 INFO - PID 8461 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 22:46:44 INFO - PID 8461 | 22:46:44 INFO - PID 8461 | (/builds/slave/test/build/application/firefox/firefox:8521): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 22:46:44 INFO - PID 8461 | 22:46:55 INFO - PID 8461 | RSS: Main: 223137792 22:46:55 INFO - PID 8461 | 22:49:52 INFO - PID 8461 | Cycle 1(1): loaded http://localhost:33872/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33872/tests/dromaeo/cssquery-ext.html) 22:49:54 INFO - PID 8461 | RSS: Main: 230236160 22:49:54 INFO - PID 8461 | 22:52:56 INFO - PID 8461 | Cycle 1(1): loaded http://localhost:33872/tests/dromaeo/cssquery-ext.html (next: http://localhost:33872/tests/dromaeo/cssquery-jquery.html) 22:52:57 INFO - PID 8461 | RSS: Main: 228982784 22:52:57 INFO - PID 8461 | 22:55:59 INFO - PID 8461 | Cycle 1(1): loaded http://localhost:33872/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33872/tests/dromaeo/cssquery-mootools.html) 22:55:59 INFO - PID 8461 | RSS: Main: 233852928 22:55:59 INFO - PID 8461 | 22:59:02 INFO - PID 8461 | Cycle 1(1): loaded http://localhost:33872/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33872/tests/dromaeo/cssquery-prototype.html) 22:59:02 INFO - PID 8461 | RSS: Main: 227041280 22:59:02 INFO - PID 8461 | 23:02:05 INFO - PID 8461 | Cycle 1(1): loaded http://localhost:33872/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33872/tests/dromaeo/cssquery-yui.html) 23:02:06 INFO - PID 8461 | RSS: Main: 231460864 23:02:06 INFO - PID 8461 | 23:05:08 INFO - PID 8461 | Cycle 1(1): loaded http://localhost:33872/tests/dromaeo/cssquery-yui.html (next: http://localhost:33872/tests/dromaeo/cssquery-dojo.html) 23:05:08 INFO - PID 8461 | RSS: Main: 234885120 23:05:08 INFO - PID 8461 | 23:05:08 INFO - PID 8461 | __start_tp_report 23:05:08 INFO - PID 8461 | _x_x_mozilla_page_load 23:05:08 INFO - PID 8461 | _x_x_mozilla_page_load_details 23:05:08 INFO - PID 8461 | |i|pagename|runs| 23:05:08 INFO - PID 8461 | |0;dojo.html;3095.5820964169843;3102.7672924530652;3118.0957522318527;3136.874525018999;3186.808791472511;6211.409916057979;6274.466670333021;6337.461315788158;6396.232452105743;6402.295747467775;6248.84377890553;6256.374362563753;6275.623462592248;6279.403456671609;6287.811365659038;5736.254286942692;5746.166805813175;5759.078547432412;5771.682557459338;5778.0177369847015;5813.854653633651;5828.0675091985295;5843.912341314884;5895.498882594963;5937.079752638327;5869.295684517864;5876.236089308405;5899.557533185007;5908.261467316585;5956.761729530814;5827.67947762875;5856.1508581255475;5864.560157988168;5888.146218798293;5911.55663325248;5189.195674670432;5568.192612071227;5671.092625179951;5696.487316141567;5876.706164691791;6263.780767673155;6277.1525844011385;6411.294757576663;6423.775167869102;6449.322821103763;6635.104260924762;6718.126643536358;6805.557638753465;6824.863502729967;6870.690818913161;6761.188657361099;6773.085633439521;6912.239653638096;6913.032175495435;6916.723331066752;6299.244090709099;6358.713857876407;6388.425041746219;6414.775482858077;6422.229332480132;6960.129983752031;7047.260037696071;7064.964675176591;7074.151101867808;7099.3965512930945;6564.4748420127235;6669.799906002828;6721.327867213239;6722;6766.661666916634;6656.60060396368;6706.362895524917;6754.763583274562;6823.761168359084;6882.621455819978;6611.900821487681;6658;6670.066190733204;6707.899381509281;6731.057651928734;6675.699593518303;6684.131062961784;6780.525363224527;6795.93204067953;6799.150106236721;5148.588112950955;5227.562420334439;5258.6844789312;5266.34170728659;5270.209468579744;4558.042811009659;4562.247229207143;4578.519255478193;4584.541545845389;4587.495375508692;5750.6837123958585;5823.796167134215;5856.355800861902;5858.297004359419;5859.033259512133;6744.494162937799;6809.897851532231;6909.550879192937;6917.619530925847;6943.479239057091;5640.576956728261;5767.942320576741;5796.507296879812;5850.619709718854;5858.8535286617325;5773.220615216976;5790.826275211751;5804.970975145097;5808.448197421239;5809.68046757424;6456.838579035561;6459.128017717642;6468.3855033771715;6469.611823290524;6527.412532871969;6766.729330826712;6830.248672646105;6856.794296171123;6883.380495755306;6908.032875397348;6457.225132984072;6562.409383155539;6582.440492558187;6582.670866456562;6663.13379260693;6538.869222615426;6678;6690.59856408617;6783.525153239423;6783.966080169567;6690.29751876046;6754.358335958076;6754.932450675627;6762.763303284558;6776.796696099125;6521.967390163209;6526.412622863775;6549.541532092899;6639.236487803964;6654.4676425884845;6631.602103873783;6680.331966803476;6721.22705888829;6726.192856857208;6731.225909020525;6542.640154791532;6559.180102487189;6572.309907459648;6623.536352455475;6644.169478815148;6451.161349024596;6518.804435866931;6664.833379165366;6719.798406047827;6740;6389.329120442287;6496.675166241764;6503.02454631809;6515.2833188348295;6524.641144737085 23:05:08 INFO - PID 8461 | |1;ext.html;850.1710831938858;865.5066612031137;865.5326123893097;869.6130222051186;872.9083446238145;38746.61253387465;42350.36474452886;42726.436413815194;42783.3582496262;42787;39495.60504394955;39903.20193596126;39942;40244.597554024454;40317.19365612686;7002.1597408310945;7109.6089715065655;7116.93246013097;7121.85756284874;7133.429325653935;6959.33886280805;6989.126359205099;7055.929440705604;7067.611281379522;7070.823229419254;7046.295370462964;7059.89410158848;7073.328033836778;7078.044463997346;7778.222177782205;7846.293833555007;7870.921290787076;7906.6837326506875;7907.644156012936;7925.5244685318485;7756;7758.612069396508;7834.6474408651175;7855.921440785633;7863.646135923897;38644.61355386466;39109.41335879964;40186;40297.194056118715;40332.19335613271;3438.157651375429;3497.545319108525;3498.3353162898966;3512.209752805624;3523.5947865995226;33015.17462063444;33303.666963330295;33396;33442.665573344195;33577.3284534308;5196.792128314863;5203.8178663746585;5204.817831375922;5256.185291279858;5273.419923808378;185846;186584;186585;186610;187310;41714.16571668549;42456.57543424588;42510.362344564855;48320.27519587209;48387.27419088716;50975.235371469455;51444.88440924717;51453.228201577;51637.74181129145;51676.483235167165;16214.864959452962;16348.182590870409;16455.25951332193;16467.423640172776;16490.58773530671;13347.598502157329;13382.531611393555;13445.663858403617;13567.592972210652;13602;13663.863361366459;13757.449702012007;13832.861671383358;13866.376013079436;13922.443102275998;5468.890622187534;5673.801416950387;5719.5645660641;5781.8554536136935;5806.1581070744505;993.3890657245742;1006.436395618456;1026.3021145621049;1029.7528593137592;1039.210200247802;22908;22961.88519057394;23041.88479057594;23141;23389;1111.7387413957713;1117.1844553476008;1126.3692332293942;1131.5360702112096;1132.4451019000794;12036.75926481483;12281.57014504506;12288.938555307166;12293.631191064282;12300.81548776769;233649;233840;233990;233998;237064;52337.7383113082;52362.21456678153;52729.472705272456;52910.73544632252;52988.73505632447;1394.2680092951282;1470.2354775516671;1471.065873170523;1471.3011319623092;1479.0312345413772;23013.769862301164;23148.537029259656;23471.63099306535;23523;23697.881510592335;37900;37907.810460948625;38067;38130.42804357882;38188.809055954545;195373;196832;197614;197865;197965;32813.507797383536;33652.49521257232;33676.326473469904;34108.488372674925;34170.31659366848;42584.78707606566;42812.571874282105;43065.569344306154;43146.62693551759;43490;31715.36569268648;32079.67920320767;32282.35435291328;32426.35147297088;33136;30615.387692246477;30989.845050774602;31623.20941976469;31862.840685796422;32053.51919721253;35100.12249693778;35158.12104697403;35197.296054078266;35693.82153089218;35730.64269357273;30208.848955755082;30557;30649.387012259183;30961.071167865;31063.68936310608;22739.317820465414;23329.650055248847;23474;23707.881460592587;23895.402614934766 23:05:08 INFO - PID 8461 | |2;jquery.html;557.6486813307615;570.7174948400542;573.1173992052239;576.1645613859903;585.5988647776273;21934.780652193473;22680.319590412295;23028.88485557574;23029.769702302972;23100.53798924025;23260;24259.150929717463;24385.75614243857;24641;24659.75340246597;6448.419642232187;6578.80263592093;6619.106420633225;6624.436922861562;6624.966875165617;6520.250171230322;6573.145491086176;6606.8678626427445;6612.636305003223;6624.072629831828;6317.399847014526;6599.835004124887;6620.470362371023;6631.93368066318;6671.29951355108;6500.512461565354;6573.605583664971;6578.967105164492;6586.7035983380865;6605.207375114993;5954.761809527614;6057.545684073712;6062.3634518375375;6083.7566497340495;6097.02447608385;28333.291667708265;28842.567361489593;28843.56734648982;28948.565771513444;28981.56527652087;1961.2449207055245;1996.710476980844;2004.849636277285;2009.849261305408;2009.859309848311;18494.722579161324;18738.531536711555;19006.904965475223;19043.61912761751;19048.61902761951;2376.7979721723673;2432.85402875828;2439.8780060997055;2446.4862378900452;2451.240115564163;113646.43176784145;120012;120373;127948;133831.33084334614;14160.955437957355;14381.712365752835;14598.124112553282;14614.707705845824;14650;22488;22554.0978360867;22603;22619.982100805504;22685.432864178198;9764.853527197098;10099.69700908974;10199.439030853375;10263.589456421809;10300.124489418333;9418.34071614994;9549.9017612974;9589.712308630753;9633.373830700983;9660.227181825438;8881.24509416694;8914.465132092095;8925.553722313989;9049;9106.453612783122;2609.6643504063754;2631.8815653295646;2704.0525826848434;2715.705229081423;2855.4431885782074;940.6284517615641;956.2541217850088;961.6586111930304;961.8845738511424;984.1929617713406;18755.31202191782;18801;18906.905465472584;19331.226750929804;19543.609127817363;1008.6772232885406;1015.57345914716;1020.9081182693593;1023.9641612543526;1024.636254129788;10447.320924139907;10467.267291289689;10593.89406105929;10597.20520960931;10852.131829453625;428658;433284;443261;446882;455593;104372.47813761035;107260;107909;108385.45807270914;109024.4548777251;1122.6519778868576;1141.1042331769522;1161.2625982501172;1170.836082948388;1172.6833754886304;16891.070991095607;16894.52006354997;17184.656306874043;17269.913650431667;17483.912580437016;52647.47352526426;53509.464905352004;53606.73196633992;53608.46391536191;53884.19173712476;69745.65127174332;69848;70141.64929175525;70405.64797175981;70562;14096.154230746188;14285.285735712965;14334.211618360672;14453.132812031312;14461.927690361901;22141.225057122923;22248.110075597444;22384.401558250007;22423.43941401478;22658.773412266324;18006.369777057742;18124.365647202285;18185.818141818414;18247.270109195484;18299.359522416653;15692.136932468367;16020.198990050683;16372.83627163713;16423.343066277692;16567.751483727512;18130.45608631743;18169.273229070688;18310.626702997062;18478.630427391647;18642.34751783681;13988.790168147689;14015;14113.870890328955;14219.217943013233;14246.786298205743;17600.559985999942;17744.822551774316;17895.37366192177;18004;18028 23:05:08 INFO - PID 8461 | |3;mootools.html;1327.535362623082;1373.4849431463201;1391.0401822742301;1393.1292941911306;1401.5164768154982;14868.330925108368;15296.923515382408;15365.231738413062;15471.149086800235;15485.14831684257;15925.283362248698;16006.759898601531;16074.517764467055;16173.919130404362;16199.190040497957;2615.62073499343;2675.331167208198;2690.9596356054676;2706.2287248134285;2717.8233414828073;2610.464854704781;2651.893924243028;2765.363966287755;2768.529350010493;2794.0360575601485;2775.6669199696066;2806.045944378911;2809.2415047937043;2809.6066550682817;2813.521701310782;2505;2517.697876254843;2520.206135067448;2520.823542352036;2674.3314171457137;2537.961930571043;2562.705288891783;2581.3030481769815;2582.2382397192873;2588.288220739293;14922;15129.546113616609;15154.924225378913;15191.240437978056;15291.158986255752;7773.844523109507;8047;8070.959645201795;8155.4291199616055;8171;5048.9495105048845;5071.3407257056715;5145.845624631267;5207.6875387476875;5212.739363031833;1863.105709259563;1908.1222637586727;1930.2954421636143;1968.0553334399426;1971.7831038585887;27334.5899811503;27355.589666155025;27546.724532754615;27560.862195688893;27642.861785691144;6452.870942581122;6739.393454589111;6783.796486105326;6918.619475928772;6942.06282151903;17363.56591085233;17400.65198696019;17440.215190316467;17458.214380352914;17503.562410939572;7879.724209652634;7897.289243968071;7913.485623434458;7938.285554300141;7943.165967573437;7238.963805181046;7253.455990800711;7362.226966168582;7382.667779949915;7443.441741869381;5078.111330517145;5167.018266529348;5196.53231209193;5302.045631786315;5466.535344495683;1369.705513314642;1421.7938398932097;1445.04626946215;1521.634807646157;1544.389965963438;884.7257350221323;901.5755106931033;901.6799036342135;911.2026976395654;916.3036092569695;7226.710931562784;7354.742584009534;7426.628668566658;7428.220036896048;7615.961920190363;948.8244674735196;980.7302991677345;982.219135787051;991.8859331176862;1006.4464544500496;4479.708818926824;4511.70673906195;4574;4583.587477127075;4672.883177920511;21275.255366062112;21304.78695213059;21374.893125534272;21757.67363489549;21788.56422871534;20822.063007164714;20838.27066052681;21413.464663383227;21416.678749818766;21531;10783.299085559414;10920.12638988879;10994.505247263893;11007.724806879893;11018.228723989405;7891.960540197262;8007.359411247203;8057.476264042818;8059.556724380215;8107.716229931924;17795;18483.353082642043;18713.53216169607;18809.623807524047;18860.717089243397;18840.52898677544;19114.33099841499;19207.135678894203;19425.61148777045;19451;11059.557617695524;11119.83320250213;11161.944190278997;11201.775964480828;11223.326600404002;13873.4450621974;14019.228942408265;14032.158070515801;14039.50861719835;14048.086874353134;10229.590816367261;11054.889451105708;11173.497192626513;11401.087912967108;11499.137564682514;10972.615958441416;11095.389753563308;11271.323720576791;11371.658850234506;11455.427228638368;9786.951065244628;9944.651937182163;9947.552360143665;9947.701568952943;9968.90342062387;6954.9304506956305;6977.302269772982;7003.299670032956;7021.227664956748;7120.501564890616;7231.855362892607;7350.338469537767;7352.301531354513;7353.779386618196;7419.925800741924 23:05:08 INFO - PID 8461 | |4;prototype.html;486.9999999999998;523.8873642166934;526.9209618557215;529.7139544645892;530.6656806212086;6309.842253943653;6347.682615869206;6357.396047375503;6377.234731832181;6394.808155755323;6119.571629985903;6154.661493617849;6194.3805619438035;6209;6248.6875656217235;5145.459726728705;5165.147750621162;5180.067587834188;5195.740212989354;5199.2461093141665;5070.188769796833;5125.231215317714;5136.844210052727;5171.224316352558;5175.922361164586;4840.225563909775;4946.332245146895;4975.179095449246;4996.525330093636;5088.702380892885;4980.9252861207115;5004.449510553836;5015.949840501585;5020.774065167058;5027.748612569393;4360.215161271001;4456.041950980522;4482.081173359454;4491.30384790358;4498.685092043558;6029.336772954972;6054.303755068179;6090.360512146205;6098;6098.207233059718;5568.52667523261;5694.202811606378;5721.971390143064;5734.16854556087;5735.770569177228;6502.447291980187;6565.441937435324;6568.146141001687;6600.570962887397;6612.239592446882;4841.612670986313;4843.9031219375765;4883.4628190898975;4890.682105663121;4909.705417674951;6619.470442364599;6739.359760822714;6813.641267431162;6905.551139175937;6928.094773937157;5000.874978125503;5027.271045698353;5036.11867923112;5037.168867136955;5050.116229659795;5030.817757826908;5062.645614806928;5068.391792984792;5099.4390617032095;5121.8719532012;5585.804496842591;5715.942840571624;5724.513416359573;5751.913721294184;5761.423857614289;6592.373724496165;6623.337666233435;6707.463402927754;6724.293949135368;6729.3270672933295;1697.4313604942236;1724.834927907304;1725.55998220453;1742.4859666398443;1754.4736579026242;1183.420124139183;1206.9698257543632;1235.9567415140427;1237.4307818403636;1244.0793812578627;5554.333699890017;5581.330240371181;5588.077967135378;5668.291463567054;5716.542676585863;6617.437517810944;6694.799156025327;6714.227863795726;6719.42884854783;6768.2216545097945;5515.944840551623;5524.226608274845;5529.751161197756;5571.832845014656;5574.721263936787;3473.0898006096545;3526.8765593204116;3563.2873425315042;3583.8317817720426;3585.713142948558;6399.13611662429;6406.67966601668;6459.741610335628;6484.837879053061;6550.3449655035065;6853.211880633691;6890.2420733719255;6890.5521141126665;6928.618925959121;6941.548799328028;6505.544611877123;6537.34626537352;6540.672966351759;6563.606183628997;6569.273751393118;6211.9068213977735;6777.966110169417;6841.144856892888;6855.314468553105;6872.587644741132;6580.605163690194;6586.176727909012;6599.571027883172;6601;6693.497987651043;6132.448079672851;6260.718267678057;6304.275008373912;6399.680015999088;6450.451711604566;4936.7284799336385;4972.577330926912;5021.598272138147;5070.569001634903;5086.61850361217;6627.27690800405;6715.160604924385;6785.694643740945;6842.863142737017;6885.724571017103;6425.742970281136;6564.770233041821;6571.277159512354;6592.505562082767;6595.142631458071;6436.227652681709;6494.2856285807575;6530.706118224596;6547.479220500047;6601.636909969998;6440.871182576416;6493.772717954849;6572.309907459648;6580;6594.241662208908;6664.400203981665;6674.43267322283;6685.130932978682;6710.328967103251;6789.796306110825;6433.742650293936;6458.128152699325;6465.191851018622;6475.190601174853;6559.344065593403 23:05:08 INFO - PID 8461 | |5;yui.html;885.8272636835819;886.4326830828269;912.9680461183858;914.4102054175053;915.7435917942978;17127.22927468264;21435.785642143594;22130.778692213073;22265.2207172749;22388.10447582097;19625.803741962576;19891.104900279486;20198.899005504987;20303.08636111375;21056.368308950718;970.1026550440831;977.130353984954;977.1352353167463;978.2076518020391;982.336922577261;980.7940332530177;984.8818141822991;986.1863962231153;986.5511192407438;989.4261328429529;1019.7960407918409;1025.6615316945426;1026.5226669598635;1029.0841151375282;1033.9896601034006;1016.5171543516844;1020.7652239984845;1022.1260821997159;1026.3072426112344;1030.067788651268;745.5750222373249;745.8023623739659;751.2862780358627;751.4289140253392;755.0524092264228;18474.44576662702;19199.232030718755;19629;19736.90131549333;20006.69989950152;21248.893755531124;21413;21791.455213619785;21813;22063;17293.567660808454;17437.7384339235;17674;17722.29110835565;17772.111394430354;3641.326354624403;3660.524131862841;3673.5040769496045;3682.815859207029;3683.8158092095555;139494;139723;145689.54310456867;146049;146879.26560367236;15733.763993540106;15902.840971590367;16082.276297566403;16138.305477924883;16159.838401616069;25404.618930716053;25515.617265741028;25610.743892561208;25663.743362566507;25706.614400784005;8789.165029322205;8790.824183516423;8821.823563528693;8870.290376769843;8906.153915378029;4358.65130789536;4408.030233348658;4428.55714428561;4438.755868427203;4441.933370999438;5449.427810079964;5473.17902314648;5480.972595137078;5627.52166065889;5636.379998200195;963.1957315641517;964.7781010367655;986.980260394788;987.0672214756986;987.9456629885425;608.716946619824;611.3000614296606;627.7081157261895;628.1425853709744;634.6953462338104;11324.32054076758;11515.30908145514;11665.416729163508;11821.349825759666;12029.75940481203;678.6064082831946;691.9688614012281;705.2277755857328;708.2704814041546;713.5433322673493;5627;5663.8867222656145;5679.6876171810945;5729.197912292282;5736.1682556029145;125727.37136314259;125778.37110814387;129645;131947;132882;103176;103969;104379;104884;105786;95113.5244323774;99368.50315748519;99410.50294748625;99992;101909.49045254727;10524.210684198868;10569.682909512727;10667.413292269;10753.677389678322;10896.073833723905;43073.353899690905;43888.561114388445;44132.558674412845;44571.777141114086;45886;54859.72570137284;56393;56434;56476.43523564712;56621.71689141528;14731.779023314444;14995.775063374276;15011.474598389004;15133.848661513244;15231.390744370545;16959.23683434224;16960.321587136375;17024.319027238773;17039.574010649834;17168.570785729957;14737.1894545801;15047.946643734833;15094.698106037598;15186.936914415885;15187.164705941279;16429;16525.504234872973;16802.57993550171;16867.325306987583;17087.572810679834;14515.129092254498;14683.41266349334;14686.045407048508;14702.55892323232;15000.549983500512;11705.122115841175;11949.283043017447;12184;12201.69495762613;12486.188397754117;12364;12375.381230938237;12436.689082772642;12475.625731228078;12699.873001269869 23:05:08 INFO - PID 8461 | __end_tp_report 23:05:08 INFO - PID 8461 | __start_cc_report 23:05:08 INFO - PID 8461 | _x_x_mozilla_cycle_collect,381 23:05:08 INFO - PID 8461 | __end_cc_report 23:05:08 INFO - PID 8461 | __startTimestamp1505369108773__endTimestamp 23:05:08 INFO - PID 8461 | 23:05:08 INFO - PID 8461 | ------- Summary: start ------- 23:05:08 INFO - PID 8461 | Number of tests: 6 23:05:08 INFO - PID 8461 | 23:05:08 INFO - PID 8461 | [#0] dojo.html Cycles:165 Average:6224.25 Median:6496.68 stddev:771.99 (11.9%) stddev-sans-first:734.31 23:05:08 INFO - PID 8461 | Values: 3095.6 3102.8 3118.1 3136.9 3186.8 6211.4 6274.5 6337.5 6396.2 6402.3 6248.8 6256.4 6275.6 6279.4 6287.8 5736.3 5746.2 5759.1 5771.7 5778.0 5813.9 5828.1 5843.9 5895.5 5937.1 5869.3 5876.2 5899.6 5908.3 5956.8 5827.7 5856.2 5864.6 5888.1 5911.6 5189.2 5568.2 5671.1 5696.5 5876.7 6263.8 6277.2 6411.3 6423.8 6449.3 6635.1 6718.1 6805.6 6824.9 6870.7 6761.2 6773.1 6912.2 6913.0 6916.7 6299.2 6358.7 6388.4 6414.8 6422.2 6960.1 7047.3 7065.0 7074.2 7099.4 6564.5 6669.8 6721.3 6722.0 6766.7 6656.6 6706.4 6754.8 6823.8 6882.6 6611.9 6658.0 6670.1 6707.9 6731.1 6675.7 6684.1 6780.5 6795.9 6799.2 5148.6 5227.6 5258.7 5266.3 5270.2 4558.0 4562.2 4578.5 4584.5 4587.5 5750.7 5823.8 5856.4 5858.3 5859.0 6744.5 6809.9 6909.6 6917.6 6943.5 5640.6 5767.9 5796.5 5850.6 5858.9 5773.2 5790.8 5805.0 5808.4 5809.7 6456.8 6459.1 6468.4 6469.6 6527.4 6766.7 6830.2 6856.8 6883.4 6908.0 6457.2 6562.4 6582.4 6582.7 6663.1 6538.9 6678.0 6690.6 6783.5 6784.0 6690.3 6754.4 6754.9 6762.8 6776.8 6522.0 6526.4 6549.5 6639.2 6654.5 6631.6 6680.3 6721.2 6726.2 6731.2 6542.6 6559.2 6572.3 6623.5 6644.2 6451.2 6518.8 6664.8 6719.8 6740.0 6389.3 6496.7 6503.0 6515.3 6524.6 23:05:08 INFO - PID 8461 | 23:05:08 INFO - PID 8461 | [#1] ext.html Cycles:180 Average:37450.15 Median:3497.94 stddev:53659.78 (1534.0%) stddev-sans-first:53739.93 23:05:08 INFO - PID 8461 | Values: 850.2 865.5 865.5 869.6 872.9 38746.6 42350.4 42726.4 42783.4 42787.0 39495.6 39903.2 39942.0 40244.6 40317.2 7002.2 7109.6 7116.9 7121.9 7133.4 6959.3 6989.1 7055.9 7067.6 7070.8 7046.3 7059.9 7073.3 7078.0 7778.2 7846.3 7870.9 7906.7 7907.6 7925.5 7756.0 7758.6 7834.6 7855.9 7863.6 38644.6 39109.4 40186.0 40297.2 40332.2 3438.2 3497.5 3498.3 3512.2 3523.6 33015.2 33303.7 33396.0 33442.7 33577.3 5196.8 5203.8 5204.8 5256.2 5273.4 185846.0 186584.0 186585.0 186610.0 187310.0 41714.2 42456.6 42510.4 48320.3 48387.3 50975.2 51444.9 51453.2 51637.7 51676.5 16214.9 16348.2 16455.3 16467.4 16490.6 13347.6 13382.5 13445.7 13567.6 13602.0 13663.9 13757.4 13832.9 13866.4 13922.4 5468.9 5673.8 5719.6 5781.9 5806.2 993.4 1006.4 1026.3 1029.8 1039.2 22908.0 22961.9 23041.9 23141.0 23389.0 1111.7 1117.2 1126.4 1131.5 1132.4 12036.8 12281.6 12288.9 12293.6 12300.8 233649.0 233840.0 233990.0 233998.0 237064.0 52337.7 52362.2 52729.5 52910.7 52988.7 1394.3 1470.2 1471.1 1471.3 1479.0 23013.8 23148.5 23471.6 23523.0 23697.9 37900.0 37907.8 38067.0 38130.4 38188.8 195373.0 196832.0 197614.0 197865.0 197965.0 32813.5 33652.5 33676.3 34108.5 34170.3 42584.8 42812.6 43065.6 43146.6 43490.0 31715.4 32079.7 32282.4 32426.4 33136.0 30615.4 30989.8 31623.2 31862.8 32053.5 35100.1 35158.1 35197.3 35693.8 35730.6 30208.8 30557.0 30649.4 30961.1 31063.7 22739.3 23329.7 23474.0 23707.9 23895.4 23:05:08 INFO - PID 8461 | 23:05:08 INFO - PID 8461 | [#2] jquery.html Cycles:180 Average:32429.77 Median:22578.55 stddev:74398.02 (329.5%) stddev-sans-first:74568.24 23:05:08 INFO - PID 8461 | Values: 557.6 570.7 573.1 576.2 585.6 21934.8 22680.3 23028.9 23029.8 23100.5 23260.0 24259.2 24385.8 24641.0 24659.8 6448.4 6578.8 6619.1 6624.4 6625.0 6520.3 6573.1 6606.9 6612.6 6624.1 6317.4 6599.8 6620.5 6631.9 6671.3 6500.5 6573.6 6579.0 6586.7 6605.2 5954.8 6057.5 6062.4 6083.8 6097.0 28333.3 28842.6 28843.6 28948.6 28981.6 1961.2 1996.7 2004.8 2009.8 2009.9 18494.7 18738.5 19006.9 19043.6 19048.6 2376.8 2432.9 2439.9 2446.5 2451.2 113646.4 120012.0 120373.0 127948.0 133831.3 14161.0 14381.7 14598.1 14614.7 14650.0 22488.0 22554.1 22603.0 22620.0 22685.4 9764.9 10099.7 10199.4 10263.6 10300.1 9418.3 9549.9 9589.7 9633.4 9660.2 8881.2 8914.5 8925.6 9049.0 9106.5 2609.7 2631.9 2704.1 2715.7 2855.4 940.6 956.3 961.7 961.9 984.2 18755.3 18801.0 18906.9 19331.2 19543.6 1008.7 1015.6 1020.9 1024.0 1024.6 10447.3 10467.3 10593.9 10597.2 10852.1 428658.0 433284.0 443261.0 446882.0 455593.0 104372.5 107260.0 107909.0 108385.5 109024.5 1122.7 1141.1 1161.3 1170.8 1172.7 16891.1 16894.5 17184.7 17269.9 17483.9 52647.5 53509.5 53606.7 53608.5 53884.2 69745.7 69848.0 70141.6 70405.6 70562.0 14096.2 14285.3 14334.2 14453.1 14461.9 22141.2 22248.1 22384.4 22423.4 22658.8 18006.4 18124.4 18185.8 18247.3 18299.4 15692.1 16020.2 16372.8 16423.3 16567.8 18130.5 18169.3 18310.6 18478.6 18642.3 13988.8 14015.0 14113.9 14219.2 14246.8 17600.6 17744.8 17895.4 18004.0 18028.0 23:05:08 INFO - PID 8461 | 23:05:08 INFO - PID 8461 | [#3] mootools.html Cycles:180 Average:9304.74 Median:2613.04 stddev:6780.57 (259.5%) stddev-sans-first:6773.11 23:05:08 INFO - PID 8461 | Values: 1327.5 1373.5 1391.0 1393.1 1401.5 14868.3 15296.9 15365.2 15471.1 15485.1 15925.3 16006.8 16074.5 16173.9 16199.2 2615.6 2675.3 2691.0 2706.2 2717.8 2610.5 2651.9 2765.4 2768.5 2794.0 2775.7 2806.0 2809.2 2809.6 2813.5 2505.0 2517.7 2520.2 2520.8 2674.3 2538.0 2562.7 2581.3 2582.2 2588.3 14922.0 15129.5 15154.9 15191.2 15291.2 7773.8 8047.0 8071.0 8155.4 8171.0 5048.9 5071.3 5145.8 5207.7 5212.7 1863.1 1908.1 1930.3 1968.1 1971.8 27334.6 27355.6 27546.7 27560.9 27642.9 6452.9 6739.4 6783.8 6918.6 6942.1 17363.6 17400.7 17440.2 17458.2 17503.6 7879.7 7897.3 7913.5 7938.3 7943.2 7239.0 7253.5 7362.2 7382.7 7443.4 5078.1 5167.0 5196.5 5302.0 5466.5 1369.7 1421.8 1445.0 1521.6 1544.4 884.7 901.6 901.7 911.2 916.3 7226.7 7354.7 7426.6 7428.2 7616.0 948.8 980.7 982.2 991.9 1006.4 4479.7 4511.7 4574.0 4583.6 4672.9 21275.3 21304.8 21374.9 21757.7 21788.6 20822.1 20838.3 21413.5 21416.7 21531.0 10783.3 10920.1 10994.5 11007.7 11018.2 7892.0 8007.4 8057.5 8059.6 8107.7 17795.0 18483.4 18713.5 18809.6 18860.7 18840.5 19114.3 19207.1 19425.6 19451.0 11059.6 11119.8 11161.9 11201.8 11223.3 13873.4 14019.2 14032.2 14039.5 14048.1 10229.6 11054.9 11173.5 11401.1 11499.1 10972.6 11095.4 11271.3 11371.7 11455.4 9787.0 9944.7 9947.6 9947.7 9968.9 6954.9 6977.3 7003.3 7021.2 7120.5 7231.9 7350.3 7352.3 7353.8 7419.9 23:05:08 INFO - PID 8461 | 23:05:08 INFO - PID 8461 | [#4] prototype.html Cycles:180 Average:5522.09 Median:6126.01 stddev:1550.21 (25.3%) stddev-sans-first:1507.79 23:05:08 INFO - PID 8461 | Values: 487.0 523.9 526.9 529.7 530.7 6309.8 6347.7 6357.4 6377.2 6394.8 6119.6 6154.7 6194.4 6209.0 6248.7 5145.5 5165.1 5180.1 5195.7 5199.2 5070.2 5125.2 5136.8 5171.2 5175.9 4840.2 4946.3 4975.2 4996.5 5088.7 4980.9 5004.4 5015.9 5020.8 5027.7 4360.2 4456.0 4482.1 4491.3 4498.7 6029.3 6054.3 6090.4 6098.0 6098.2 5568.5 5694.2 5722.0 5734.2 5735.8 6502.4 6565.4 6568.1 6600.6 6612.2 4841.6 4843.9 4883.5 4890.7 4909.7 6619.5 6739.4 6813.6 6905.6 6928.1 5000.9 5027.3 5036.1 5037.2 5050.1 5030.8 5062.6 5068.4 5099.4 5121.9 5585.8 5715.9 5724.5 5751.9 5761.4 6592.4 6623.3 6707.5 6724.3 6729.3 1697.4 1724.8 1725.6 1742.5 1754.5 1183.4 1207.0 1236.0 1237.4 1244.1 5554.3 5581.3 5588.1 5668.3 5716.5 6617.4 6694.8 6714.2 6719.4 6768.2 5515.9 5524.2 5529.8 5571.8 5574.7 3473.1 3526.9 3563.3 3583.8 3585.7 6399.1 6406.7 6459.7 6484.8 6550.3 6853.2 6890.2 6890.6 6928.6 6941.5 6505.5 6537.3 6540.7 6563.6 6569.3 6211.9 6778.0 6841.1 6855.3 6872.6 6580.6 6586.2 6599.6 6601.0 6693.5 6132.4 6260.7 6304.3 6399.7 6450.5 4936.7 4972.6 5021.6 5070.6 5086.6 6627.3 6715.2 6785.7 6842.9 6885.7 6425.7 6564.8 6571.3 6592.5 6595.1 6436.2 6494.3 6530.7 6547.5 6601.6 6440.9 6493.8 6572.3 6580.0 6594.2 6664.4 6674.4 6685.1 6710.3 6789.8 6433.7 6458.1 6465.2 6475.2 6559.3 23:05:08 INFO - PID 8461 | 23:05:08 INFO - PID 8461 | [#5] yui.html Cycles:180 Average:24442.98 Median:20679.73 stddev:36068.68 (174.4%) stddev-sans-first:36126.49 23:05:08 INFO - PID 8461 | Values: 885.8 886.4 913.0 914.4 915.7 17127.2 21435.8 22130.8 22265.2 22388.1 19625.8 19891.1 20198.9 20303.1 21056.4 970.1 977.1 977.1 978.2 982.3 980.8 984.9 986.2 986.6 989.4 1019.8 1025.7 1026.5 1029.1 1034.0 1016.5 1020.8 1022.1 1026.3 1030.1 745.6 745.8 751.3 751.4 755.1 18474.4 19199.2 19629.0 19736.9 20006.7 21248.9 21413.0 21791.5 21813.0 22063.0 17293.6 17437.7 17674.0 17722.3 17772.1 3641.3 3660.5 3673.5 3682.8 3683.8 139494.0 139723.0 145689.5 146049.0 146879.3 15733.8 15902.8 16082.3 16138.3 16159.8 25404.6 25515.6 25610.7 25663.7 25706.6 8789.2 8790.8 8821.8 8870.3 8906.2 4358.7 4408.0 4428.6 4438.8 4441.9 5449.4 5473.2 5481.0 5627.5 5636.4 963.2 964.8 987.0 987.1 987.9 608.7 611.3 627.7 628.1 634.7 11324.3 11515.3 11665.4 11821.3 12029.8 678.6 692.0 705.2 708.3 713.5 5627.0 5663.9 5679.7 5729.2 5736.2 125727.4 125778.4 129645.0 131947.0 132882.0 103176.0 103969.0 104379.0 104884.0 105786.0 95113.5 99368.5 99410.5 99992.0 101909.5 10524.2 10569.7 10667.4 10753.7 10896.1 43073.4 43888.6 44132.6 44571.8 45886.0 54859.7 56393.0 56434.0 56476.4 56621.7 14731.8 14995.8 15011.5 15133.8 15231.4 16959.2 16960.3 17024.3 17039.6 17168.6 14737.2 15047.9 15094.7 15186.9 15187.2 16429.0 16525.5 16802.6 16867.3 17087.6 14515.1 14683.4 14686.0 14702.6 15000.5 11705.1 11949.3 12184.0 12201.7 12486.2 12364.0 12375.4 12436.7 12475.6 12699.9 23:05:08 INFO - PID 8461 | -------- Summary: end -------- 23:05:08 INFO - PID 8461 | 23:05:09 INFO - TEST-INFO | 8461: exit 0 23:05:09 INFO - TEST-OK | dromaeo_css | took 1112598ms 23:05:09 INFO - TEST-START | kraken 23:05:09 INFO - Initialising browser for kraken test... 23:05:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33872/getInfo.html -profile /tmp/tmpGjg3nM/profile 23:05:09 INFO - TEST-INFO | started process 8842 (/builds/slave/test/build/application/firefox/firefox http://localhost:33872/getInfo.html) 23:05:15 INFO - TEST-INFO | 8842: exit 0 23:05:15 INFO - Browser initialized. 23:05:15 INFO - Running cycle 1/1 for kraken test... 23:05:15 INFO - Using env: {'DISPLAY': ':0', 23:05:15 INFO - 'HOME': '/home/cltbld', 23:05:15 INFO - 'JSGC_DISABLE_POISONING': '1', 23:05:15 INFO - 'LANG': 'en_US.UTF-8', 23:05:15 INFO - 'LANGUAGE': 'en_US:en', 23:05:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 23:05:15 INFO - 'LOGNAME': 'cltbld', 23:05:15 INFO - 'MAIL': '/var/mail/cltbld', 23:05:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:05:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:05:15 INFO - 'MOZ_ACCELERATED': '1', 23:05:15 INFO - 'MOZ_CRASHREPORTER': '1', 23:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 23:05:15 INFO - 'MOZ_NO_REMOTE': '1', 23:05:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:05:15 INFO - 'MOZ_WEBRENDER': '1', 23:05:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:05:15 INFO - 'NO_EM_RESTART': '1', 23:05:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:15 INFO - 'PWD': '/builds/slave/test', 23:05:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:05:15 INFO - 'RUST_BACKTRACE': 'full', 23:05:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 23:05:15 INFO - 'SHELL': '/bin/bash', 23:05:15 INFO - 'SHLVL': '1', 23:05:15 INFO - 'TERM': 'linux', 23:05:15 INFO - 'TMOUT': '86400', 23:05:15 INFO - 'USER': 'cltbld', 23:05:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882', 23:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:15 INFO - '_': '/tools/buildbot/bin/python'} 23:05:15 INFO - TEST-INFO | started process 9083 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGjg3nM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 23:05:16 INFO - PID 9083 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 23:05:16 INFO - PID 9083 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 23:05:16 INFO - PID 9083 | 23:05:16 INFO - PID 9083 | (/builds/slave/test/build/application/firefox/firefox:9143): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 23:05:16 INFO - PID 9083 | 23:05:26 INFO - PID 9083 | RSS: Main: 223330304 23:05:26 INFO - PID 9083 | 23:05:29 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33872/tests/kraken/driver.html?testName=audio-beat-detection) 23:05:30 INFO - PID 9083 | RSS: Main: 228950016 23:05:30 INFO - PID 9083 | 23:05:33 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33872/tests/kraken/driver.html?testName=audio-dft) 23:05:33 INFO - PID 9083 | RSS: Main: 229654528 23:05:33 INFO - PID 9083 | 23:05:36 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33872/tests/kraken/driver.html?testName=audio-fft) 23:05:37 INFO - PID 9083 | RSS: Main: 225722368 23:05:37 INFO - PID 9083 | 23:05:39 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33872/tests/kraken/driver.html?testName=audio-oscillator) 23:05:40 INFO - PID 9083 | RSS: Main: 225267712 23:05:40 INFO - PID 9083 | 23:05:42 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33872/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:05:43 INFO - PID 9083 | RSS: Main: 226095104 23:05:43 INFO - PID 9083 | 23:05:46 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33872/tests/kraken/driver.html?testName=imaging-darkroom) 23:05:47 INFO - PID 9083 | RSS: Main: 228642816 23:05:47 INFO - PID 9083 | 23:05:50 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33872/tests/kraken/driver.html?testName=imaging-desaturate) 23:05:51 INFO - PID 9083 | RSS: Main: 228442112 23:05:51 INFO - PID 9083 | 23:05:54 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33872/tests/kraken/driver.html?testName=json-parse-financial) 23:05:55 INFO - PID 9083 | RSS: Main: 228515840 23:05:55 INFO - PID 9083 | 23:05:56 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33872/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:05:57 INFO - PID 9083 | RSS: Main: 228847616 23:05:57 INFO - PID 9083 | 23:05:59 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33872/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:05:59 INFO - PID 9083 | RSS: Main: 225759232 23:05:59 INFO - PID 9083 | 23:06:01 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33872/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:06:02 INFO - PID 9083 | RSS: Main: 226369536 23:06:02 INFO - PID 9083 | 23:06:05 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33872/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:06:05 INFO - PID 9083 | RSS: Main: 226439168 23:06:05 INFO - PID 9083 | 23:06:08 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33872/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:06:09 INFO - PID 9083 | RSS: Main: 225738752 23:06:09 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | Cycle 1(1): loaded http://localhost:33872/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33872/tests/kraken/driver.html?testName=ai-astar) 23:06:11 INFO - PID 9083 | RSS: Main: 227667968 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | __start_tp_report 23:06:11 INFO - PID 9083 | _x_x_mozilla_page_load 23:06:11 INFO - PID 9083 | _x_x_mozilla_page_load_details 23:06:11 INFO - PID 9083 | |i|pagename|runs| 23:06:11 INFO - PID 9083 | |0;ai-astar;115;112;108;100;112;109;110;109;108;108 23:06:11 INFO - PID 9083 | |1;audio-beat-detection;119;123;129;117;124;121;144;126;125;121 23:06:11 INFO - PID 9083 | |2;audio-dft;165;166;165;170;167;169;177;167;165;174 23:06:11 INFO - PID 9083 | |3;audio-fft;82;82;74;79;82;75;74;77;83;89 23:06:11 INFO - PID 9083 | |4;audio-oscillator;79;80;82;78;81;82;89;80;79;90 23:06:11 INFO - PID 9083 | |5;imaging-gaussian-blur;121;116;113;114;114;110;114;114;115;115 23:06:11 INFO - PID 9083 | |6;imaging-darkroom;151;152;152;148;151;157;148;150;148;149 23:06:11 INFO - PID 9083 | |7;imaging-desaturate;97;94;95;95;94;94;95;97;100;94 23:06:11 INFO - PID 9083 | |8;json-parse-financial;53;59;59;56;52;56;56;54;54;54 23:06:11 INFO - PID 9083 | |9;json-stringify-tinderbox;44;39;50;38;39;42;38;39;45;48 23:06:11 INFO - PID 9083 | |10;stanford-crypto-aes;74;75;77;81;73;76;81;84;83;74 23:06:11 INFO - PID 9083 | |11;stanford-crypto-ccm;125;117;123;111;116;120;119;114;115;112 23:06:11 INFO - PID 9083 | |12;stanford-crypto-pbkdf2;175;171;175;169;166;164;169;176;177;173 23:06:11 INFO - PID 9083 | |13;stanford-crypto-sha256-iterative;68;69;63;66;68;66;64;65;66;68 23:06:11 INFO - PID 9083 | __end_tp_report 23:06:11 INFO - PID 9083 | __start_cc_report 23:06:11 INFO - PID 9083 | _x_x_mozilla_cycle_collect,937 23:06:11 INFO - PID 9083 | __end_cc_report 23:06:11 INFO - PID 9083 | __startTimestamp1505369171483__endTimestamp 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | ------- Summary: start ------- 23:06:11 INFO - PID 9083 | Number of tests: 14 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#0] ai-astar Cycles:10 Average:109.10 Median:109.50 stddev:3.93 (3.6%) stddev-sans-first:3.54 23:06:11 INFO - PID 9083 | Values: 115.0 112.0 108.0 100.0 112.0 109.0 110.0 109.0 108.0 108.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:124.50 stddev:7.56 (6.1%) stddev-sans-first:7.72 23:06:11 INFO - PID 9083 | Values: 119.0 123.0 129.0 117.0 124.0 121.0 144.0 126.0 125.0 121.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#2] audio-dft Cycles:10 Average:168.50 Median:168.00 stddev:4.12 (2.5%) stddev-sans-first:4.17 23:06:11 INFO - PID 9083 | Values: 165.0 166.0 165.0 170.0 167.0 169.0 177.0 167.0 165.0 174.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#3] audio-fft Cycles:10 Average:79.70 Median:82.00 stddev:4.81 (5.9%) stddev-sans-first:5.03 23:06:11 INFO - PID 9083 | Values: 82.0 82.0 74.0 79.0 82.0 75.0 74.0 77.0 83.0 89.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:81.50 stddev:4.16 (5.1%) stddev-sans-first:4.27 23:06:11 INFO - PID 9083 | Values: 79.0 80.0 82.0 78.0 81.0 82.0 89.0 80.0 79.0 90.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.50 stddev:2.76 (2.4%) stddev-sans-first:1.69 23:06:11 INFO - PID 9083 | Values: 121.0 116.0 113.0 114.0 114.0 110.0 114.0 114.0 115.0 115.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:151.00 stddev:2.76 (1.8%) stddev-sans-first:2.92 23:06:11 INFO - PID 9083 | Values: 151.0 152.0 152.0 148.0 151.0 157.0 148.0 150.0 148.0 149.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:1.96 (2.1%) stddev-sans-first:2.00 23:06:11 INFO - PID 9083 | Values: 97.0 94.0 95.0 95.0 94.0 94.0 95.0 97.0 100.0 94.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:2.36 (4.2%) stddev-sans-first:2.35 23:06:11 INFO - PID 9083 | Values: 53.0 59.0 59.0 56.0 52.0 56.0 56.0 54.0 54.0 54.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#9] json-stringify-tinderbox Cycles:10 Average:42.20 Median:43.00 stddev:4.37 (10.2%) stddev-sans-first:4.58 23:06:11 INFO - PID 9083 | Values: 44.0 39.0 50.0 38.0 39.0 42.0 38.0 39.0 45.0 48.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#10] stanford-crypto-aes Cycles:10 Average:77.80 Median:79.00 stddev:4.08 (5.2%) stddev-sans-first:4.09 23:06:11 INFO - PID 9083 | Values: 74.0 75.0 77.0 81.0 73.0 76.0 81.0 84.0 83.0 74.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#11] stanford-crypto-ccm Cycles:10 Average:117.20 Median:118.00 stddev:4.57 (3.9%) stddev-sans-first:3.87 23:06:11 INFO - PID 9083 | Values: 125.0 117.0 123.0 111.0 116.0 120.0 119.0 114.0 115.0 112.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.50 Median:174.00 stddev:4.43 (2.5%) stddev-sans-first:4.51 23:06:11 INFO - PID 9083 | Values: 175.0 171.0 175.0 169.0 166.0 164.0 169.0 176.0 177.0 173.0 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.00 stddev:1.95 (2.9%) stddev-sans-first:1.96 23:06:11 INFO - PID 9083 | Values: 68.0 69.0 63.0 66.0 68.0 66.0 64.0 65.0 66.0 68.0 23:06:11 INFO - PID 9083 | -------- Summary: end -------- 23:06:11 INFO - PID 9083 | 23:06:11 INFO - PID 9083 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 23:06:12 INFO - TEST-INFO | 9083: exit 0 23:06:12 INFO - TEST-OK | kraken | took 62662ms 23:06:12 INFO - SUITE-END | took 1175s 23:06:12 INFO - Completed test suite (00:19:35) 23:06:12 INFO - javascript benchmark 23:06:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8456.318005864157, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3095.5820964169843, 3102.7672924530652, 3118.0957522318527, 3136.874525018999, 3186.808791472511, 6211.409916057979, 6274.466670333021, 6337.461315788158, 6396.232452105743, 6402.295747467775, 6248.84377890553, 6256.374362563753, 6275.623462592248, 6279.403456671609, 6287.811365659038, 5736.254286942692, 5746.166805813175, 5759.078547432412, 5771.682557459338, 5778.0177369847015, 5813.854653633651, 5828.0675091985295, 5843.912341314884, 5895.498882594963, 5937.079752638327, 5869.295684517864, 5876.236089308405, 5899.557533185007, 5908.261467316585, 5956.761729530814, 5827.67947762875, 5856.1508581255475, 5864.560157988168, 5888.146218798293, 5911.55663325248, 5189.195674670432, 5568.192612071227, 5671.092625179951, 5696.487316141567, 5876.706164691791, 6263.780767673155, 6277.1525844011385, 6411.294757576663, 6423.775167869102, 6449.322821103763, 6635.104260924762, 6718.126643536358, 6805.557638753465, 6824.863502729967, 6870.690818913161, 6761.188657361099, 6773.085633439521, 6912.239653638096, 6913.032175495435, 6916.723331066752, 6299.244090709099, 6358.713857876407, 6388.425041746219, 6414.775482858077, 6422.229332480132, 6960.129983752031, 7047.260037696071, 7064.964675176591, 7074.151101867808, 7099.3965512930945, 6564.4748420127235, 6669.799906002828, 6721.327867213239, 6722.0, 6766.661666916634, 6656.60060396368, 6706.362895524917, 6754.763583274562, 6823.761168359084, 6882.621455819978, 6611.900821487681, 6658.0, 6670.066190733204, 6707.899381509281, 6731.057651928734, 6675.699593518303, 6684.131062961784, 6780.525363224527, 6795.93204067953, 6799.150106236721, 5148.588112950955, 5227.562420334439, 5258.6844789312, 5266.34170728659, 5270.209468579744, 4558.042811009659, 4562.247229207143, 4578.519255478193, 4584.541545845389, 4587.495375508692, 5750.6837123958585, 5823.796167134215, 5856.355800861902, 5858.297004359419, 5859.033259512133, 6744.494162937799, 6809.897851532231, 6909.550879192937, 6917.619530925847, 6943.479239057091, 5640.576956728261, 5767.942320576741, 5796.507296879812, 5850.619709718854, 5858.8535286617325, 5773.220615216976, 5790.826275211751, 5804.970975145097, 5808.448197421239, 5809.68046757424, 6456.838579035561, 6459.128017717642, 6468.3855033771715, 6469.611823290524, 6527.412532871969, 6766.729330826712, 6830.248672646105, 6856.794296171123, 6883.380495755306, 6908.032875397348, 6457.225132984072, 6562.409383155539, 6582.440492558187, 6582.670866456562, 6663.13379260693, 6538.869222615426, 6678.0, 6690.59856408617, 6783.525153239423, 6783.966080169567, 6690.29751876046, 6754.358335958076, 6754.932450675627, 6762.763303284558, 6776.796696099125, 6521.967390163209, 6526.412622863775, 6549.541532092899, 6639.236487803964, 6654.4676425884845, 6631.602103873783, 6680.331966803476, 6721.22705888829, 6726.192856857208, 6731.225909020525, 6542.640154791532, 6559.180102487189, 6572.309907459648, 6623.536352455475, 6644.169478815148, 6451.161349024596, 6518.804435866931, 6664.833379165366, 6719.798406047827, 6740.0, 6389.329120442287, 6496.675166241764, 6503.02454631809, 6515.2833188348295, 6524.641144737085], "value": 6163.41621914403, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [850.1710831938858, 865.5066612031137, 865.5326123893097, 869.6130222051186, 872.9083446238145, 38746.61253387465, 42350.36474452886, 42726.436413815194, 42783.3582496262, 42787.0, 39495.60504394955, 39903.20193596126, 39942.0, 40244.597554024454, 40317.19365612686, 7002.1597408310945, 7109.6089715065655, 7116.93246013097, 7121.85756284874, 7133.429325653935, 6959.33886280805, 6989.126359205099, 7055.929440705604, 7067.611281379522, 7070.823229419254, 7046.295370462964, 7059.89410158848, 7073.328033836778, 7078.044463997346, 7778.222177782205, 7846.293833555007, 7870.921290787076, 7906.6837326506875, 7907.644156012936, 7925.5244685318485, 7756.0, 7758.612069396508, 7834.6474408651175, 7855.921440785633, 7863.646135923897, 38644.61355386466, 39109.41335879964, 40186.0, 40297.194056118715, 40332.19335613271, 3438.157651375429, 3497.545319108525, 3498.3353162898966, 3512.209752805624, 3523.5947865995226, 33015.17462063444, 33303.666963330295, 33396.0, 33442.665573344195, 33577.3284534308, 5196.792128314863, 5203.8178663746585, 5204.817831375922, 5256.185291279858, 5273.419923808378, 185846.0, 186584.0, 186585.0, 186610.0, 187310.0, 41714.16571668549, 42456.57543424588, 42510.362344564855, 48320.27519587209, 48387.27419088716, 50975.235371469455, 51444.88440924717, 51453.228201577, 51637.74181129145, 51676.483235167165, 16214.864959452962, 16348.182590870409, 16455.25951332193, 16467.423640172776, 16490.58773530671, 13347.598502157329, 13382.531611393555, 13445.663858403617, 13567.592972210652, 13602.0, 13663.863361366459, 13757.449702012007, 13832.861671383358, 13866.376013079436, 13922.443102275998, 5468.890622187534, 5673.801416950387, 5719.5645660641, 5781.8554536136935, 5806.1581070744505, 993.3890657245742, 1006.436395618456, 1026.3021145621049, 1029.7528593137592, 1039.210200247802, 22908.0, 22961.88519057394, 23041.88479057594, 23141.0, 23389.0, 1111.7387413957713, 1117.1844553476008, 1126.3692332293942, 1131.5360702112096, 1132.4451019000794, 12036.75926481483, 12281.57014504506, 12288.938555307166, 12293.631191064282, 12300.81548776769, 233649.0, 233840.0, 233990.0, 233998.0, 237064.0, 52337.7383113082, 52362.21456678153, 52729.472705272456, 52910.73544632252, 52988.73505632447, 1394.2680092951282, 1470.2354775516671, 1471.065873170523, 1471.3011319623092, 1479.0312345413772, 23013.769862301164, 23148.537029259656, 23471.63099306535, 23523.0, 23697.881510592335, 37900.0, 37907.810460948625, 38067.0, 38130.42804357882, 38188.809055954545, 195373.0, 196832.0, 197614.0, 197865.0, 197965.0, 32813.507797383536, 33652.49521257232, 33676.326473469904, 34108.488372674925, 34170.31659366848, 42584.78707606566, 42812.571874282105, 43065.569344306154, 43146.62693551759, 43490.0, 31715.36569268648, 32079.67920320767, 32282.35435291328, 32426.35147297088, 33136.0, 30615.387692246477, 30989.845050774602, 31623.20941976469, 31862.840685796422, 32053.51919721253, 35100.12249693778, 35158.12104697403, 35197.296054078266, 35693.82153089218, 35730.64269357273, 30208.848955755082, 30557.0, 30649.387012259183, 30961.071167865, 31063.68936310608, 22739.317820465414, 23329.650055248847, 23474.0, 23707.881460592587, 23895.402614934766], "value": 17053.81911477294, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.6486813307615, 570.7174948400542, 573.1173992052239, 576.1645613859903, 585.5988647776273, 21934.780652193473, 22680.319590412295, 23028.88485557574, 23029.769702302972, 23100.53798924025, 23260.0, 24259.150929717463, 24385.75614243857, 24641.0, 24659.75340246597, 6448.419642232187, 6578.80263592093, 6619.106420633225, 6624.436922861562, 6624.966875165617, 6520.250171230322, 6573.145491086176, 6606.8678626427445, 6612.636305003223, 6624.072629831828, 6317.399847014526, 6599.835004124887, 6620.470362371023, 6631.93368066318, 6671.29951355108, 6500.512461565354, 6573.605583664971, 6578.967105164492, 6586.7035983380865, 6605.207375114993, 5954.761809527614, 6057.545684073712, 6062.3634518375375, 6083.7566497340495, 6097.02447608385, 28333.291667708265, 28842.567361489593, 28843.56734648982, 28948.565771513444, 28981.56527652087, 1961.2449207055245, 1996.710476980844, 2004.849636277285, 2009.849261305408, 2009.859309848311, 18494.722579161324, 18738.531536711555, 19006.904965475223, 19043.61912761751, 19048.61902761951, 2376.7979721723673, 2432.85402875828, 2439.8780060997055, 2446.4862378900452, 2451.240115564163, 113646.43176784145, 120012.0, 120373.0, 127948.0, 133831.33084334614, 14160.955437957355, 14381.712365752835, 14598.124112553282, 14614.707705845824, 14650.0, 22488.0, 22554.0978360867, 22603.0, 22619.982100805504, 22685.432864178198, 9764.853527197098, 10099.69700908974, 10199.439030853375, 10263.589456421809, 10300.124489418333, 9418.34071614994, 9549.9017612974, 9589.712308630753, 9633.373830700983, 9660.227181825438, 8881.24509416694, 8914.465132092095, 8925.553722313989, 9049.0, 9106.453612783122, 2609.6643504063754, 2631.8815653295646, 2704.0525826848434, 2715.705229081423, 2855.4431885782074, 940.6284517615641, 956.2541217850088, 961.6586111930304, 961.8845738511424, 984.1929617713406, 18755.31202191782, 18801.0, 18906.905465472584, 19331.226750929804, 19543.609127817363, 1008.6772232885406, 1015.57345914716, 1020.9081182693593, 1023.9641612543526, 1024.636254129788, 10447.320924139907, 10467.267291289689, 10593.89406105929, 10597.20520960931, 10852.131829453625, 428658.0, 433284.0, 443261.0, 446882.0, 455593.0, 104372.47813761035, 107260.0, 107909.0, 108385.45807270914, 109024.4548777251, 1122.6519778868576, 1141.1042331769522, 1161.2625982501172, 1170.836082948388, 1172.6833754886304, 16891.070991095607, 16894.52006354997, 17184.656306874043, 17269.913650431667, 17483.912580437016, 52647.47352526426, 53509.464905352004, 53606.73196633992, 53608.46391536191, 53884.19173712476, 69745.65127174332, 69848.0, 70141.64929175525, 70405.64797175981, 70562.0, 14096.154230746188, 14285.285735712965, 14334.211618360672, 14453.132812031312, 14461.927690361901, 22141.225057122923, 22248.110075597444, 22384.401558250007, 22423.43941401478, 22658.773412266324, 18006.369777057742, 18124.365647202285, 18185.818141818414, 18247.270109195484, 18299.359522416653, 15692.136932468367, 16020.198990050683, 16372.83627163713, 16423.343066277692, 16567.751483727512, 18130.45608631743, 18169.273229070688, 18310.626702997062, 18478.630427391647, 18642.34751783681, 13988.790168147689, 14015.0, 14113.870890328955, 14219.217943013233, 14246.786298205743, 17600.559985999942, 17744.822551774316, 17895.37366192177, 18004.0, 18028.0], "value": 11685.117720303757, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.535362623082, 1373.4849431463201, 1391.0401822742301, 1393.1292941911306, 1401.5164768154982, 14868.330925108368, 15296.923515382408, 15365.231738413062, 15471.149086800235, 15485.14831684257, 15925.283362248698, 16006.759898601531, 16074.517764467055, 16173.919130404362, 16199.190040497957, 2615.62073499343, 2675.331167208198, 2690.9596356054676, 2706.2287248134285, 2717.8233414828073, 2610.464854704781, 2651.893924243028, 2765.363966287755, 2768.529350010493, 2794.0360575601485, 2775.6669199696066, 2806.045944378911, 2809.2415047937043, 2809.6066550682817, 2813.521701310782, 2505.0, 2517.697876254843, 2520.206135067448, 2520.823542352036, 2674.3314171457137, 2537.961930571043, 2562.705288891783, 2581.3030481769815, 2582.2382397192873, 2588.288220739293, 14922.0, 15129.546113616609, 15154.924225378913, 15191.240437978056, 15291.158986255752, 7773.844523109507, 8047.0, 8070.959645201795, 8155.4291199616055, 8171.0, 5048.9495105048845, 5071.3407257056715, 5145.845624631267, 5207.6875387476875, 5212.739363031833, 1863.105709259563, 1908.1222637586727, 1930.2954421636143, 1968.0553334399426, 1971.7831038585887, 27334.5899811503, 27355.589666155025, 27546.724532754615, 27560.862195688893, 27642.861785691144, 6452.870942581122, 6739.393454589111, 6783.796486105326, 6918.619475928772, 6942.06282151903, 17363.56591085233, 17400.65198696019, 17440.215190316467, 17458.214380352914, 17503.562410939572, 7879.724209652634, 7897.289243968071, 7913.485623434458, 7938.285554300141, 7943.165967573437, 7238.963805181046, 7253.455990800711, 7362.226966168582, 7382.667779949915, 7443.441741869381, 5078.111330517145, 5167.018266529348, 5196.53231209193, 5302.045631786315, 5466.535344495683, 1369.705513314642, 1421.7938398932097, 1445.04626946215, 1521.634807646157, 1544.389965963438, 884.7257350221323, 901.5755106931033, 901.6799036342135, 911.2026976395654, 916.3036092569695, 7226.710931562784, 7354.742584009534, 7426.628668566658, 7428.220036896048, 7615.961920190363, 948.8244674735196, 980.7302991677345, 982.219135787051, 991.8859331176862, 1006.4464544500496, 4479.708818926824, 4511.70673906195, 4574.0, 4583.587477127075, 4672.883177920511, 21275.255366062112, 21304.78695213059, 21374.893125534272, 21757.67363489549, 21788.56422871534, 20822.063007164714, 20838.27066052681, 21413.464663383227, 21416.678749818766, 21531.0, 10783.299085559414, 10920.12638988879, 10994.505247263893, 11007.724806879893, 11018.228723989405, 7891.960540197262, 8007.359411247203, 8057.476264042818, 8059.556724380215, 8107.716229931924, 17795.0, 18483.353082642043, 18713.53216169607, 18809.623807524047, 18860.717089243397, 18840.52898677544, 19114.33099841499, 19207.135678894203, 19425.61148777045, 19451.0, 11059.557617695524, 11119.83320250213, 11161.944190278997, 11201.775964480828, 11223.326600404002, 13873.4450621974, 14019.228942408265, 14032.158070515801, 14039.50861719835, 14048.086874353134, 10229.590816367261, 11054.889451105708, 11173.497192626513, 11401.087912967108, 11499.137564682514, 10972.615958441416, 11095.389753563308, 11271.323720576791, 11371.658850234506, 11455.427228638368, 9786.951065244628, 9944.651937182163, 9947.552360143665, 9947.701568952943, 9968.90342062387, 6954.9304506956305, 6977.302269772982, 7003.299670032956, 7021.227664956748, 7120.501564890616, 7231.855362892607, 7350.338469537767, 7352.301531354513, 7353.779386618196, 7419.925800741924], "value": 6609.238606054513, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.9999999999998, 523.8873642166934, 526.9209618557215, 529.7139544645892, 530.6656806212086, 6309.842253943653, 6347.682615869206, 6357.396047375503, 6377.234731832181, 6394.808155755323, 6119.571629985903, 6154.661493617849, 6194.3805619438035, 6209.0, 6248.6875656217235, 5145.459726728705, 5165.147750621162, 5180.067587834188, 5195.740212989354, 5199.2461093141665, 5070.188769796833, 5125.231215317714, 5136.844210052727, 5171.224316352558, 5175.922361164586, 4840.225563909775, 4946.332245146895, 4975.179095449246, 4996.525330093636, 5088.702380892885, 4980.9252861207115, 5004.449510553836, 5015.949840501585, 5020.774065167058, 5027.748612569393, 4360.215161271001, 4456.041950980522, 4482.081173359454, 4491.30384790358, 4498.685092043558, 6029.336772954972, 6054.303755068179, 6090.360512146205, 6098.0, 6098.207233059718, 5568.52667523261, 5694.202811606378, 5721.971390143064, 5734.16854556087, 5735.770569177228, 6502.447291980187, 6565.441937435324, 6568.146141001687, 6600.570962887397, 6612.239592446882, 4841.612670986313, 4843.9031219375765, 4883.4628190898975, 4890.682105663121, 4909.705417674951, 6619.470442364599, 6739.359760822714, 6813.641267431162, 6905.551139175937, 6928.094773937157, 5000.874978125503, 5027.271045698353, 5036.11867923112, 5037.168867136955, 5050.116229659795, 5030.817757826908, 5062.645614806928, 5068.391792984792, 5099.4390617032095, 5121.8719532012, 5585.804496842591, 5715.942840571624, 5724.513416359573, 5751.913721294184, 5761.423857614289, 6592.373724496165, 6623.337666233435, 6707.463402927754, 6724.293949135368, 6729.3270672933295, 1697.4313604942236, 1724.834927907304, 1725.55998220453, 1742.4859666398443, 1754.4736579026242, 1183.420124139183, 1206.9698257543632, 1235.9567415140427, 1237.4307818403636, 1244.0793812578627, 5554.333699890017, 5581.330240371181, 5588.077967135378, 5668.291463567054, 5716.542676585863, 6617.437517810944, 6694.799156025327, 6714.227863795726, 6719.42884854783, 6768.2216545097945, 5515.944840551623, 5524.226608274845, 5529.751161197756, 5571.832845014656, 5574.721263936787, 3473.0898006096545, 3526.8765593204116, 3563.2873425315042, 3583.8317817720426, 3585.713142948558, 6399.13611662429, 6406.67966601668, 6459.741610335628, 6484.837879053061, 6550.3449655035065, 6853.211880633691, 6890.2420733719255, 6890.5521141126665, 6928.618925959121, 6941.548799328028, 6505.544611877123, 6537.34626537352, 6540.672966351759, 6563.606183628997, 6569.273751393118, 6211.9068213977735, 6777.966110169417, 6841.144856892888, 6855.314468553105, 6872.587644741132, 6580.605163690194, 6586.176727909012, 6599.571027883172, 6601.0, 6693.497987651043, 6132.448079672851, 6260.718267678057, 6304.275008373912, 6399.680015999088, 6450.451711604566, 4936.7284799336385, 4972.577330926912, 5021.598272138147, 5070.569001634903, 5086.61850361217, 6627.27690800405, 6715.160604924385, 6785.694643740945, 6842.863142737017, 6885.724571017103, 6425.742970281136, 6564.770233041821, 6571.277159512354, 6592.505562082767, 6595.142631458071, 6436.227652681709, 6494.2856285807575, 6530.706118224596, 6547.479220500047, 6601.636909969998, 6440.871182576416, 6493.772717954849, 6572.309907459648, 6580.0, 6594.241662208908, 6664.400203981665, 6674.43267322283, 6685.130932978682, 6710.328967103251, 6789.796306110825, 6433.742650293936, 6458.128152699325, 6465.191851018622, 6475.190601174853, 6559.344065593403], "value": 5065.825820790299, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [885.8272636835819, 886.4326830828269, 912.9680461183858, 914.4102054175053, 915.7435917942978, 17127.22927468264, 21435.785642143594, 22130.778692213073, 22265.2207172749, 22388.10447582097, 19625.803741962576, 19891.104900279486, 20198.899005504987, 20303.08636111375, 21056.368308950718, 970.1026550440831, 977.130353984954, 977.1352353167463, 978.2076518020391, 982.336922577261, 980.7940332530177, 984.8818141822991, 986.1863962231153, 986.5511192407438, 989.4261328429529, 1019.7960407918409, 1025.6615316945426, 1026.5226669598635, 1029.0841151375282, 1033.9896601034006, 1016.5171543516844, 1020.7652239984845, 1022.1260821997159, 1026.3072426112344, 1030.067788651268, 745.5750222373249, 745.8023623739659, 751.2862780358627, 751.4289140253392, 755.0524092264228, 18474.44576662702, 19199.232030718755, 19629.0, 19736.90131549333, 20006.69989950152, 21248.893755531124, 21413.0, 21791.455213619785, 21813.0, 22063.0, 17293.567660808454, 17437.7384339235, 17674.0, 17722.29110835565, 17772.111394430354, 3641.326354624403, 3660.524131862841, 3673.5040769496045, 3682.815859207029, 3683.8158092095555, 139494.0, 139723.0, 145689.54310456867, 146049.0, 146879.26560367236, 15733.763993540106, 15902.840971590367, 16082.276297566403, 16138.305477924883, 16159.838401616069, 25404.618930716053, 25515.617265741028, 25610.743892561208, 25663.743362566507, 25706.614400784005, 8789.165029322205, 8790.824183516423, 8821.823563528693, 8870.290376769843, 8906.153915378029, 4358.65130789536, 4408.030233348658, 4428.55714428561, 4438.755868427203, 4441.933370999438, 5449.427810079964, 5473.17902314648, 5480.972595137078, 5627.52166065889, 5636.379998200195, 963.1957315641517, 964.7781010367655, 986.980260394788, 987.0672214756986, 987.9456629885425, 608.716946619824, 611.3000614296606, 627.7081157261895, 628.1425853709744, 634.6953462338104, 11324.32054076758, 11515.30908145514, 11665.416729163508, 11821.349825759666, 12029.75940481203, 678.6064082831946, 691.9688614012281, 705.2277755857328, 708.2704814041546, 713.5433322673493, 5627.0, 5663.8867222656145, 5679.6876171810945, 5729.197912292282, 5736.1682556029145, 125727.37136314259, 125778.37110814387, 129645.0, 131947.0, 132882.0, 103176.0, 103969.0, 104379.0, 104884.0, 105786.0, 95113.5244323774, 99368.50315748519, 99410.50294748625, 99992.0, 101909.49045254727, 10524.210684198868, 10569.682909512727, 10667.413292269, 10753.677389678322, 10896.073833723905, 43073.353899690905, 43888.561114388445, 44132.558674412845, 44571.777141114086, 45886.0, 54859.72570137284, 56393.0, 56434.0, 56476.43523564712, 56621.71689141528, 14731.779023314444, 14995.775063374276, 15011.474598389004, 15133.848661513244, 15231.390744370545, 16959.23683434224, 16960.321587136375, 17024.319027238773, 17039.574010649834, 17168.570785729957, 14737.1894545801, 15047.946643734833, 15094.698106037598, 15186.936914415885, 15187.164705941279, 16429.0, 16525.504234872973, 16802.57993550171, 16867.325306987583, 17087.572810679834, 14515.129092254498, 14683.41266349334, 14686.045407048508, 14702.55892323232, 15000.549983500512, 11705.122115841175, 11949.283043017447, 12184.0, 12201.69495762613, 12486.188397754117, 12364.0, 12375.381230938237, 12436.689082772642, 12475.625731228078, 12699.873001269869], "value": 8891.697622566486, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1455.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 112.0, 108.0, 100.0, 112.0, 109.0, 110.0, 109.0, 108.0, 108.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 123.0, 129.0, 117.0, 124.0, 121.0, 144.0, 126.0, 125.0, 121.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 165.0, 170.0, 167.0, 169.0, 177.0, 167.0, 165.0, 174.0], "value": 168.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 74.0, 79.0, 82.0, 75.0, 74.0, 77.0, 83.0, 89.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 82.0, 78.0, 81.0, 82.0, 89.0, 80.0, 79.0, 90.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 116.0, 113.0, 114.0, 114.0, 110.0, 114.0, 114.0, 115.0, 115.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 152.0, 152.0, 148.0, 151.0, 157.0, 148.0, 150.0, 148.0, 149.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 97.0, 100.0, 94.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 59.0, 59.0, 56.0, 52.0, 56.0, 56.0, 54.0, 54.0, 54.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 39.0, 50.0, 38.0, 39.0, 42.0, 38.0, 39.0, 45.0, 48.0], "value": 42.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 77.0, 81.0, 73.0, 76.0, 81.0, 84.0, 83.0, 74.0], "value": 77.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 117.0, 123.0, 111.0, 116.0, 120.0, 119.0, 114.0, 115.0, 112.0], "value": 117.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 171.0, 175.0, 169.0, 166.0, 164.0, 169.0, 176.0, 177.0, 173.0], "value": 171.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 63.0, 66.0, 68.0, 66.0, 64.0, 65.0, 66.0, 68.0], "value": 66.3, "unit": "score"}]}]} 23:06:12 INFO - Return code: 0 23:06:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:06:12 INFO - # TBPL SUCCESS # 23:06:12 INFO - Running post-action listener: _package_coverage_data 23:06:12 INFO - Running post-action listener: _resource_record_post_action 23:06:12 INFO - [mozharness: 2017-09-14 06:06:12.761152Z] Finished run-tests step (success) 23:06:12 INFO - Running post-run listener: _resource_record_post_run 23:06:12 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 332566528; Read time: 12; Write time: 201448 23:06:12 INFO - TinderboxPrint: CPU usage
13.3% 23:06:12 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 23:06:12 INFO - TinderboxPrint: I/O write bytes / time
332,566,528 / 201,448 23:06:12 INFO - TinderboxPrint: CPU idle
8,243.4 (86.7%) 23:06:12 INFO - TinderboxPrint: CPU user
1,202.4 (12.6%) 23:06:12 INFO - TinderboxPrint: Swap in / out
0 / 0 23:06:12 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13111296; Read time: 0; Write time: 1268 23:06:12 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 23:06:12 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 319062016; Read time: 0; Write time: 200152 23:06:13 INFO - Running post-run listener: _upload_blobber_files 23:06:13 INFO - Blob upload gear active. 23:06:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:06:13 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 23:06:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:06:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:06:13 INFO - (blobuploader) - INFO - Open directory for files ... 23:06:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:06:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:06:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:14 INFO - (blobuploader) - INFO - Done attempting. 23:06:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:06:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:06:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:16 INFO - (blobuploader) - INFO - Done attempting. 23:06:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:06:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:06:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:24 INFO - (blobuploader) - INFO - Done attempting. 23:06:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:06:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:06:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:25 INFO - (blobuploader) - INFO - Done attempting. 23:06:25 INFO - (blobuploader) - INFO - Iteration through files over. 23:06:25 INFO - Return code: 0 23:06:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:06:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:06:25 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baccdd677f6b550b850b8dc38b59c0d5ad7df35136d749301f846a32cfd28594a101c385327ed9ce8d39d8ea0abc5199205d5b35c971e548a18e82b924ea03f3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1c969d3738b3e4f1ed0a2ba27d52e79d9e1d42c36ee16bd1312a1d0dce047aa6104a4c6b667176297555702662be527c485435bb01979baf8992372b3525d8f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ca766591596edf9ca956cc306d7f48588bd45c084117bf16f36715399faebfec5f37cd8d7b3e4df7289e6b1d36e895ef2971261b2ba0c557658329444a43c79", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/168d0b527d584e265558ef73a6aabb52676480257407bcab6d34e141818824b73b8e7f8cff992a0b330e380a9cecb384ca7ed33953af6218bbb8b8ff95d73efb"} 23:06:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:06:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:06:25 INFO - Contents: 23:06:25 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baccdd677f6b550b850b8dc38b59c0d5ad7df35136d749301f846a32cfd28594a101c385327ed9ce8d39d8ea0abc5199205d5b35c971e548a18e82b924ea03f3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1c969d3738b3e4f1ed0a2ba27d52e79d9e1d42c36ee16bd1312a1d0dce047aa6104a4c6b667176297555702662be527c485435bb01979baf8992372b3525d8f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ca766591596edf9ca956cc306d7f48588bd45c084117bf16f36715399faebfec5f37cd8d7b3e4df7289e6b1d36e895ef2971261b2ba0c557658329444a43c79", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/168d0b527d584e265558ef73a6aabb52676480257407bcab6d34e141818824b73b8e7f8cff992a0b330e380a9cecb384ca7ed33953af6218bbb8b8ff95d73efb"} 23:06:25 INFO - Running post-run listener: copy_logs_to_upload_dir 23:06:25 INFO - Copying logs to upload dir... 23:06:25 INFO - mkdir: /builds/slave/test/build/upload/logs 23:06:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.131770 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-13 23:06:25.102528) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 23:06:25.110180) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baccdd677f6b550b850b8dc38b59c0d5ad7df35136d749301f846a32cfd28594a101c385327ed9ce8d39d8ea0abc5199205d5b35c971e548a18e82b924ea03f3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1c969d3738b3e4f1ed0a2ba27d52e79d9e1d42c36ee16bd1312a1d0dce047aa6104a4c6b667176297555702662be527c485435bb01979baf8992372b3525d8f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ca766591596edf9ca956cc306d7f48588bd45c084117bf16f36715399faebfec5f37cd8d7b3e4df7289e6b1d36e895ef2971261b2ba0c557658329444a43c79", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/168d0b527d584e265558ef73a6aabb52676480257407bcab6d34e141818824b73b8e7f8cff992a0b330e380a9cecb384ca7ed33953af6218bbb8b8ff95d73efb"} build_url:https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011499 build_url: 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baccdd677f6b550b850b8dc38b59c0d5ad7df35136d749301f846a32cfd28594a101c385327ed9ce8d39d8ea0abc5199205d5b35c971e548a18e82b924ea03f3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e1c969d3738b3e4f1ed0a2ba27d52e79d9e1d42c36ee16bd1312a1d0dce047aa6104a4c6b667176297555702662be527c485435bb01979baf8992372b3525d8f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ca766591596edf9ca956cc306d7f48588bd45c084117bf16f36715399faebfec5f37cd8d7b3e4df7289e6b1d36e895ef2971261b2ba0c557658329444a43c79", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/168d0b527d584e265558ef73a6aabb52676480257407bcab6d34e141818824b73b8e7f8cff992a0b330e380a9cecb384ca7ed33953af6218bbb8b8ff95d73efb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 23:06:25.141508) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:06:25.141859) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1505365042.333056-1119321882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004524 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:06:25.191595) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 23:06:25.194937) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 23:06:25.195241) ========= ========= Total master_lag: 0.23 =========