builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-013 starttime: 1505367926.75 results: success (0) revision: 6f08f91f8db00d385e5d564b0102bf0dece39f6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:26.750503) ========= 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:26.751025) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:26.751303) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:26.903689) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:26.904014) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 22:45:26-- 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% 46.2M=0s 2017-09-13 22:45:27 (46.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.188475 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:27.118828) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:27.119151) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.024113 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:27.180864) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-13 22:45:27.181154) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 22:45:27,243 truncating revision to first 12 chars 2017-09-13 22:45:27,243 Setting DEBUG logging. 2017-09-13 22:45:27,243 attempt 1/10 2017-09-13 22:45:27,243 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:27,625 attempt 1/10 2017-09-13 22:45:28,512 current task status: no status available at this point. state: STARTED 2017-09-13 22:45:28,513 sleeping for 10.00s (attempt 1/10) 2017-09-13 22:45:38,523 attempt 2/10 2017-09-13 22:45:39,020 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-13 22:45:39,283 unpacking tar archive at: mozilla-central-6f08f91f8db0/testing/mozharness/ program finished with exit code 0 elapsedTime=12.313553 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-09-13 22:45:39.517145) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:39.517422) ========= script_repo_revision: 6f08f91f8db00d385e5d564b0102bf0dece39f6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:39.517778) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:39.518036) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 22:45:39.537109) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-13 22:45:39.537373) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:45:39 INFO - MultiFileLogger online at 20170913 22:45:39 in /builds/slave/test 22:45:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 22:45:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:45:39 INFO - {'append_to_log': False, 22:45:39 INFO - 'base_work_dir': '/builds/slave/test', 22:45:39 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 22:45:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:45:39 INFO - 'branch': 'Firefox-Non-PGO', 22:45:39 INFO - 'buildbot_json_path': 'buildprops.json', 22:45:39 INFO - 'code_coverage': False, 22:45:39 INFO - 'config_files': ('talos/linux_config.py',), 22:45:39 INFO - 'default_actions': ('clobber', 22:45:39 INFO - 'read-buildbot-config', 22:45:39 INFO - 'download-and-extract', 22:45:39 INFO - 'populate-webroot', 22:45:39 INFO - 'create-virtualenv', 22:45:39 INFO - 'install', 22:45:39 INFO - 'setup-mitmproxy', 22:45:39 INFO - 'run-tests'), 22:45:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:45:39 INFO - 'disable_ccov_upload': False, 22:45:39 INFO - 'disable_stylo': False, 22:45:39 INFO - 'download_minidump_stackwalk': True, 22:45:39 INFO - 'download_symbols': 'ondemand', 22:45:39 INFO - 'enable_stylo': False, 22:45:39 INFO - 'enable_webrender': False, 22:45:39 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 22:45:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:45:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:45:39 INFO - 'gecko_profile': False, 22:45:39 INFO - 'gecko_profile_interval': 0, 22:45:39 INFO - 'installer_path': 'installer.exe', 22:45:39 INFO - 'log_level': 'info', 22:45:39 INFO - 'log_name': 'talos', 22:45:39 INFO - 'log_to_console': True, 22:45:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:45:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:45:39 INFO - 'opt_config_files': (), 22:45:39 INFO - 'pip_index': False, 22:45:39 INFO - 'suite': 'dromaeojs-e10s', 22:45:39 INFO - 'system_bits': '32', 22:45:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:45:39 INFO - 'title': 'talos-linux64-ix-013', 22:45:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:45:39 INFO - 'use_talos_json': True, 22:45:39 INFO - 'verify': 'False', 22:45:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:45:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:45:39 INFO - 'work_dir': 'build'} 22:45:39 INFO - [mozharness: 2017-09-14 05:45:39.737514Z] Running clobber step. 22:45:39 INFO - Running pre-action listener: _resource_record_pre_action 22:45:39 INFO - Running main action method: clobber 22:45:39 INFO - rmtree: /builds/slave/test/build 22:45:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:45:40 INFO - Running post-action listener: _resource_record_post_action 22:45:40 INFO - [mozharness: 2017-09-14 05:45:40.732558Z] Finished clobber step (success) 22:45:40 INFO - [mozharness: 2017-09-14 05:45:40.732657Z] Running read-buildbot-config step. 22:45:40 INFO - Running pre-action listener: _resource_record_pre_action 22:45:40 INFO - Running main action method: read_buildbot_config 22:45:40 INFO - Using buildbot properties: 22:45:40 INFO - { 22:45:40 INFO - "project": "", 22:45:40 INFO - "product": "firefox", 22:45:40 INFO - "who": "jdolske@mozilla.com", 22:45:40 INFO - "installer_path": "public/build/target.tar.bz2", 22:45:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 22:45:40 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 22:45:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 22:45:40 INFO - "stage_platform": "linux64", 22:45:40 INFO - "basedir": "/builds/slave/test", 22:45:40 INFO - "buildnumber": 821, 22:45:40 INFO - "platform": "ubuntu64_hw", 22:45:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:45:40 INFO - "slavebuilddir": "test", 22:45:40 INFO - "taskId": "QSaEyW1RTi-GYBuWgRqN3w", 22:45:40 INFO - "branch": "mozilla-central", 22:45:40 INFO - "script_repo_revision": "production", 22:45:40 INFO - "revision": "6f08f91f8db00d385e5d564b0102bf0dece39f6f", 22:45:40 INFO - "slavename": "talos-linux64-ix-013", 22:45:40 INFO - "repo_path": "mozilla-central" 22:45:40 INFO - } 22:45:40 INFO - Finding installer, test and symbols from parent task. 22:45:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QSaEyW1RTi-GYBuWgRqN3w'}, attempt #1 22:45:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QSaEyW1RTi-GYBuWgRqN3w'}, attempt #1 22:45:42 INFO - Task dependencies: XkYgQGM7RR6WsMLSikInwA 22:45:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA'}, attempt #1 22:45:42 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2 22:45:42 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.test_packages.json 22:45:42 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.crashreporter-symbols.zip 22:45:42 INFO - Running post-action listener: _resource_record_post_action 22:45:42 INFO - [mozharness: 2017-09-14 05:45:42.818831Z] Finished read-buildbot-config step (success) 22:45:42 INFO - [mozharness: 2017-09-14 05:45:42.819047Z] Running download-and-extract step. 22:45:42 INFO - Running pre-action listener: _resource_record_pre_action 22:45:42 INFO - Running main action method: download_and_extract 22:45:42 INFO - mkdir: /builds/slave/test/build/tests 22:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:45:42 INFO - trying https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.test_packages.json 22:45:42 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:42 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:43 INFO - Downloaded 989 bytes. 22:45:43 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 22:45:43 INFO - Using the following test package requirements: 22:45:43 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 22:45:43 INFO - u'common': [u'target.common.tests.zip'], 22:45:43 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 22:45:43 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 22:45:43 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 22:45:43 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 22:45:43 INFO - u'mozbase': [u'target.common.tests.zip'], 22:45:43 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 22:45:43 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 22:45:43 INFO - u'web-platform': [u'target.common.tests.zip', 22:45:43 INFO - u'target.web-platform.tests.tar.gz'], 22:45:43 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 22:45:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 22:45:43 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:43 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:43 INFO - Fetch https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.common.tests.zip into memory 22:45:44 INFO - Content-Length response header: 54572630 22:45:44 INFO - Bytes received: 54572630 22:45:55 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:55 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:55 INFO - Fetch https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.talos.tests.zip into memory 22:45:56 INFO - Content-Length response header: 14500356 22:45:56 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.964888Z] Finished download-and-extract step (success) 22:45:57 INFO - [mozharness: 2017-09-14 05:45:57.964986Z] 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.979982Z] Finished populate-webroot step (success) 22:45:57 INFO - [mozharness: 2017-09-14 05:45:57.980071Z] 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:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:45:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:45:58 INFO - Installing distribute.............................................................................................................................................................................................done. 22:45:59 INFO - Installing pip...............done. 22:45:59 INFO - Return code: 0 22:45:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:45:59 INFO - Reading from file tmpfile_stdout 22:45:59 INFO - Output received: 22:45:59 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:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:45:59 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:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:45:59 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:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:45:59 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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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:59 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:59 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:59 INFO - Using env: {'DISPLAY': ':0', 22:45:59 INFO - 'HOME': '/home/cltbld', 22:45:59 INFO - 'LANG': 'en_US.UTF-8', 22:45:59 INFO - 'LANGUAGE': 'en_US:en', 22:45:59 INFO - 'LOGNAME': 'cltbld', 22:45:59 INFO - 'MAIL': '/var/mail/cltbld', 22:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:45:59 INFO - 'MOZ_NO_REMOTE': '1', 22:45:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:45:59 INFO - 'NO_EM_RESTART': '1', 22:45:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:45:59 INFO - 'PWD': '/builds/slave/test', 22:45:59 INFO - 'SHELL': '/bin/bash', 22:45:59 INFO - 'SHLVL': '1', 22:45:59 INFO - 'TERM': 'linux', 22:45:59 INFO - 'TMOUT': '86400', 22:45:59 INFO - 'USER': 'cltbld', 22:45:59 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 22:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:45:59 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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 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:02 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:02 INFO - building 'psutil._psutil_posix' extension 22:46:02 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:02 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:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:46:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:46:02 INFO - Successfully installed psutil 22:46:02 INFO - Cleaning up... 22:46:02 INFO - Return code: 0 22:46:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:46:02 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:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:46:02 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:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:46:02 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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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:02 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:02 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:02 INFO - Using env: {'DISPLAY': ':0', 22:46:02 INFO - 'HOME': '/home/cltbld', 22:46:02 INFO - 'LANG': 'en_US.UTF-8', 22:46:02 INFO - 'LANGUAGE': 'en_US:en', 22:46:02 INFO - 'LOGNAME': 'cltbld', 22:46:02 INFO - 'MAIL': '/var/mail/cltbld', 22:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:02 INFO - 'MOZ_NO_REMOTE': '1', 22:46:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:02 INFO - 'NO_EM_RESTART': '1', 22:46:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:02 INFO - 'PWD': '/builds/slave/test', 22:46:02 INFO - 'SHELL': '/bin/bash', 22:46:02 INFO - 'SHLVL': '1', 22:46:02 INFO - 'TERM': 'linux', 22:46:02 INFO - 'TMOUT': '86400', 22:46:02 INFO - 'USER': 'cltbld', 22:46:02 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 22:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:02 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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 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:04 INFO - Downloading/unpacking jsonschema==2.5.1 22:46:04 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:04 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:04 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:04 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 - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:46:05 INFO - Downloading/unpacking functools32 (from 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 functools32-3.2.3-2.tar.gz 22:46:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:46:06 INFO - warning: no files found matching '*.txt' 22:46:06 INFO - no previously-included directories found matching 'build' 22:46:06 INFO - no previously-included directories found matching 'dist' 22:46:06 INFO - no previously-included directories found matching '.git*' 22:46:06 INFO - Installing collected packages: jsonschema, functools32 22:46:06 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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 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:09 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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:10 INFO - 'PWD': '/builds/slave/test', 22:46:10 INFO - 'SHELL': '/bin/bash', 22:46:10 INFO - 'SHLVL': '1', 22:46:10 INFO - 'TERM': 'linux', 22:46:10 INFO - 'TMOUT': '86400', 22:46:10 INFO - 'USER': 'cltbld', 22:46:10 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 22:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:10 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-Kx6em3-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-DQjXXe-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-gaPABa-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-bfi1N4-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-2BDIvm-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-F8WjT_-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-VG97rp-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-biAA8m-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-oCpun6-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-bbzSPW-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-7FZhXR-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-U16kzd-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-qxYg4W-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-HCA6OI-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-jDEBs1-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-0PkHHH-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-dKl4FX-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:13 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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 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-1JJgZX-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-Py2osC-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-q5hGsA-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-CyHl54-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-3YJOK9-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-SwzSSy-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-TNymYM-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-OUrsao-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-4ONLyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:46:14 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:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:46:14 INFO - Running setup.py (path:/tmp/pip-_BOCTG-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-GjG94A-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-ttqngG-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-nNlqWn-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-0Y8kgw-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-IOADgg-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-L7d2x4-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-Eb30BN-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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 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 0x13561f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x151fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c2a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 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.848767Z] Finished create-virtualenv step (success) 22:46:18 INFO - [mozharness: 2017-09-14 05:46:18.849283Z] 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:19 INFO - Reading from file tmpfile_stdout 22:46:19 INFO - Detecting whether we're running mozinstall >=1.0... 22:46:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:46:19 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.919252Z] Finished install step (success) 22:46:36 INFO - [mozharness: 2017-09-14 05:46:36.919504Z] 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.920630Z] Finished setup-mitmproxy step (success) 22:46:36 INFO - [mozharness: 2017-09-14 05:46:36.920849Z] 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 0x16c6090>, '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 0x16c6d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16c0f90>, '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:37 INFO - Return code: 0 22:46:37 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:46:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:46:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:46:37 INFO - ENV: RUST_BACKTRACE is now full 22:46:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:46:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:46:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-013 --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:37 INFO - Using env: {'DISPLAY': ':0', 22:46:37 INFO - 'HOME': '/home/cltbld', 22:46:37 INFO - 'LANG': 'en_US.UTF-8', 22:46:37 INFO - 'LANGUAGE': 'en_US:en', 22:46:37 INFO - 'LOGNAME': 'cltbld', 22:46:37 INFO - 'MAIL': '/var/mail/cltbld', 22:46:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:46:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:46:37 INFO - 'MOZ_NO_REMOTE': '1', 22:46:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:46:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:46:37 INFO - 'NO_EM_RESTART': '1', 22:46:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:46:37 INFO - 'PWD': '/builds/slave/test', 22:46:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:46:37 INFO - 'RUST_BACKTRACE': 'full', 22:46:37 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:46:37 INFO - 'SHELL': '/bin/bash', 22:46:37 INFO - 'SHLVL': '1', 22:46:37 INFO - 'TERM': 'linux', 22:46:37 INFO - 'TMOUT': '86400', 22:46:37 INFO - 'USER': 'cltbld', 22:46:37 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 22:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:37 INFO - '_': '/tools/buildbot/bin/python'} 22:46:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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:50669' 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:50669/getInfo.html -profile /tmp/tmp2Q49ZA/profile 22:46:37 INFO - TEST-INFO | started process 22631 (/builds/slave/test/build/application/firefox/firefox http://localhost:50669/getInfo.html) 22:46:42 INFO - TEST-INFO | 22631: 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_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 - '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': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 22:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:46:43 INFO - '_': '/tools/buildbot/bin/python'} 22:46:43 INFO - TEST-INFO | started process 22862 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2Q49ZA/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 22862 | 22:46:44 INFO - PID 22862 | (/builds/slave/test/build/application/firefox/firefox:22911): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 22:46:44 INFO - PID 22862 | 22:46:54 INFO - PID 22862 | RSS: Main: 175906816 22:46:54 INFO - PID 22862 | 22:49:51 INFO - PID 22862 | Cycle 1(1): loaded http://localhost:50669/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50669/tests/dromaeo/cssquery-ext.html) 22:49:53 INFO - PID 22862 | RSS: Main: 177647616 22:49:53 INFO - PID 22862 | 22:52:54 INFO - PID 22862 | Cycle 1(1): loaded http://localhost:50669/tests/dromaeo/cssquery-ext.html (next: http://localhost:50669/tests/dromaeo/cssquery-jquery.html) 22:52:55 INFO - PID 22862 | RSS: Main: 172941312 22:52:55 INFO - PID 22862 | 22:55:57 INFO - PID 22862 | Cycle 1(1): loaded http://localhost:50669/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50669/tests/dromaeo/cssquery-mootools.html) 22:55:57 INFO - PID 22862 | RSS: Main: 177504256 22:55:57 INFO - PID 22862 | 22:58:59 INFO - PID 22862 | Cycle 1(1): loaded http://localhost:50669/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50669/tests/dromaeo/cssquery-prototype.html) 22:58:59 INFO - PID 22862 | RSS: Main: 175534080 22:58:59 INFO - PID 22862 | 23:02:03 INFO - PID 22862 | Cycle 1(1): loaded http://localhost:50669/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50669/tests/dromaeo/cssquery-yui.html) 23:02:03 INFO - PID 22862 | RSS: Main: 176521216 23:02:03 INFO - PID 22862 | 23:05:05 INFO - PID 22862 | Cycle 1(1): loaded http://localhost:50669/tests/dromaeo/cssquery-yui.html (next: http://localhost:50669/tests/dromaeo/cssquery-dojo.html) 23:05:05 INFO - PID 22862 | RSS: Main: 179732480 23:05:05 INFO - PID 22862 | 23:05:05 INFO - PID 22862 | __start_tp_report 23:05:05 INFO - PID 22862 | _x_x_mozilla_page_load 23:05:05 INFO - PID 22862 | _x_x_mozilla_page_load_details 23:05:05 INFO - PID 22862 | |i|pagename|runs| 23:05:05 INFO - PID 22862 | |0;dojo.html;3124.3438877835656;3142.7800053996216;3169.0809665197094;3169.7305729013033;3174.063651222889;6110.847228819276;6114.633122012682;6164.321924588292;6244.094606282092;6262.906056409158;6223.315435302125;6246.750129994806;6264.96867515663;6276.68616569172;6298.748050078003;5731.598788084836;5745.482906538411;5755.971220143893;5777.537796976232;5791.913121303184;5745.080787074069;5775.884482310351;5822.126680997863;5830.679312637803;5832.125181222808;5751.309842818847;5807.622504537213;5824.941750582503;5828.562857785661;5883.852903677421;5509.889802203974;5752;5757.596968212227;5819.30168379795;5830.0380437227595;5638.830835074954;5664.716764161775;5694;5729.197912292282;5784.855378615526;6164.075388691687;6213.471854892339;6215.81352559424;6275.341089185661;6317.87364252717;6497.870042599169;6566.540342176049;6637.767678131242;6671.5997040177745;6702.128723265993;6693.698783554752;6748.021536877174;6753.189617245962;6753.797386078426;6785.355391237825;6170.259568851767;6252.812415627538;6256.749730010794;6276.937230627681;6284.2458904931245;6821.693023813841;6857.211420686585;6927.203371612329;6938.757143500055;6939.930600693929;6454.5159113065765;6526.151600292027;6550.2794692583775;6563.04835798816;6577.506686998494;6586.308437614078;6618.86762264752;6671.232808227019;6702.463802895757;6723.630200339028;6603.405693487609;6605.86788264232;6609.041688955074;6686.19765628118;6708.1950165979415;6556.344365563501;6649.833754156088;6657.034729964128;6711.261761206263;6722.563033402813;5200.323957885526;5221.895562088737;5229.294045303911;5230.895382092337;5246.947530524646;4519.638428925678;4599.839005634787;4605.148047611203;4637.304404339308;4649.976750116228;5536.252605898233;5589.552835773128;5683.232763576864;5752.02215623345;5757.395473475309;6649.168853893263;6724.058631791553;6732.461403087741;6732.798016059526;6756.324367563303;5623.915641265385;5686.3744988051285;5696.430356964271;5755.65466072037;5761.798337058183;5722.570807189478;5762.9135562966585;5771.971140144273;5796.42035796417;5799.3620701722775;6411.67941602918;6469.450096741735;6492.318306577836;6500.414962653384;6510.804675859732;6603.80188594343;6629.933700662931;6721.395074443323;6722.630255336004;6735.158105236846;6390.840228994312;6433;6463.030545418225;6487.935120648733;6501.934980650133;6510.3489651034515;6607.174103237096;6627.072209890715;6627.801165965029;6643.70103345341;6623.205215374186;6651.06885036105;6665.20017597872;6711.395974362331;6729.1252137221845;6388.265349484683;6397.264314603694;6397.520185986163;6474.643894585843;6520.282768895323;6477.740890364333;6491.415772580491;6502.992036234391;6506.316836732075;6529.804105876831;6366.5225108118;6369.840753981188;6447.838804029937;6456.418922296828;6456.709448074754;6502.382273683992;6509.1863517060365;6537.934620653923;6563.3764792344655;6580.440662543546;6227.813165605039;6245.96877015612;6303.9684801575695;6332.271788744353;6345.587536810092 23:05:05 INFO - PID 22862 | |1;ext.html;844.700131453334;861.4228466927158;863.8704194370845;891.3315013739697;908.7864351877307;40338.193236135296;41081.79459102701;41186;41237.175256494855;41291.174176516455;37033.62966370335;37131.81434092832;37347.81326093366;37421;37449.06377340568;6856.382925536688;6963.512554121214;6964.721411143548;6980.057692211537;6996.3353481419435;6951.89572156418;6977.092977912865;6982.3715865572085;7026.964865175667;7031.613261270628;6946.340097690711;6958.582485050913;6973.23294437614;7578.65896034677;7788.104367997667;7695.230476952288;7709.730159444449;7712.57580833054;7720.189380115091;7755.844883102334;7635.274648908345;7665.156832748393;7711.9974403327205;7717.536947783151;7718.845623087563;37769.81115094434;38474.230515389536;38503.22993540142;38516.42225366622;38655.613443865484;3485.2158264390564;3531.593866705336;3531.7351198660203;3532.0640030391965;3534.840932158059;30835.845820770977;30967.38065238705;30984.0704778857;31037.689623103706;31070.378592428024;5194.065068287707;5196.558292545138;5230;5230.660007099526;5263.157894736823;187193;187246;187561;187615;187723;47247.527524725;47318.526814732104;47341;47412.7629361851;47629.761851190524;48823;48840;48841.511584883694;48949.265761013616;49015.509844901804;16020.439284624983;16219.918900405659;16225.51323460298;16268.75596866048;16278.67442651164;13285.26931018784;13317.667058323426;13358.265295408846;13365.26491043002;13372.331383430985;13938.094023888414;14151;14194.787078193835;14241.715165696834;14301.57095287143;5666.34836993751;5736.741846616912;5761.193432919395;5805.622634528742;5984.73068711909;1005.7535903703639;1016.654337525245;1022.5143057047843;1027.9074883260541;1032.3135115148493;22493;22511;22682.206122785625;22741.772582274298;22751.544969100527;1105.8728246251624;1109.7336639206694;1109.9611513596985;1121.5850135449937;1131.7510147767478;12002.579909703118;12013.699657508632;12049.096317776202;12087.214331068453;12093.153479256367;208549;210507;210878;211070;211425;44019.33970990438;44784;51242.48757512377;51339.48660513422;51515.7424212884;1458.2417143085536;1467.2370367408887;1467.9853201468063;1477.4754961988554;1493.8282097558701;26046.218613441626;26559;26602.866985664947;26877.731222688304;27300.72699272982;36063.45904811377;36216.27567448689;36269.81865090658;36275.27449451049;36377.09057273589;186455;186522;187443;187478;187671;33416.832915835264;33424.33151337008;33433;33450;33672.49491257682;42109.78945105255;42161.94595135024;42590.78704606457;42870;42934.35598465963;30741;30764;31294;31302.530462042632;31684.207894802814;29721.405571888874;31097.844510778206;31320.843395782875;31393.686063139077;31402;34405.139871503416;34530.65469345274;34537.65462345345;34673.653263467044;34750.47874281837;30372;30551.54172687456;30705.232369190948;30801.691983079883;31111.533327000565;22734.886325568266;22772.31683049511;22835.40152189331;22854.88572557193;23169.768302317436 23:05:05 INFO - PID 22862 | |2;jquery.html;557.3590371073266;568.5331843842886;572.493343391099;573.9024365857804;583.2825624481889;22583.77416225837;23219.419514512145;23401.180958666406;23433.765662343372;23469.413264668372;23651;24733.628995565083;24831.50336993259;24885;25059.49881002379;6598.769043083493;6606.7687630932805;6631.237407698104;6631.767888123916;6638.56849304796;6180.196574445338;6565.967170164143;6652.101966234569;6654.301298363651;6657.034729964153;6436.549441539094;6448.5163612729;6450.677466126698;6480.513961452886;6481.027845823117;6400.7119679614525;6404.391582799627;6405.007223880283;6458.063580780784;6479.967600161993;5879.353271140171;5972.074328479092;6102.664353460558;6111.480524155408;6133.1413602095745;28381.716182838114;28818.135455936146;28824;28892.133236002952;28895.711042889514;2053.1068984991575;2075.8754474731563;2076.3044380132674;2076.9376918692465;2082.0526660369496;18553.165107570192;19005;19185.80814191854;19271.13279902408;19292.1318540666;2393.34183099649;2418.069043418279;2430.987845060781;2434.7565243475688;2440.3411079008747;125012;128895;129842;138575.30712346375;143718;14480.685849428624;14482.420703171754;14682.853171468149;14701.839104592564;14862;22054.117835286408;22437.21469748551;22500.549989000127;22504.887475562846;22543.43641408945;9805.313628046111;9874.21006319507;9977.60089596408;10033.849492257623;10045.79908401828;9167.44995300284;9184.357095003286;9194.264458843276;9249.445033298023;9353.204977576983;8724.389292749574;8779.29765618749;8838.425502342327;9052.321075919332;9143.31425143117;2562.167295628928;2607.381093742987;2609.178108895692;2680.2444398918738;2680.249530131566;938.5635679409107;953.6233187890745;958.750724811554;974.6929717139078;974.907383798538;18840.623187536174;18939.621207575772;19176.904115479334;19261.711074333896;19559.0220488975;997.0926456924165;1012.9898701013043;1021.6985989133224;1028.8096702110133;1030.9484525773682;10321.225908056926;10370.066693997576;10527.21045921559;10566.260361774603;10569.418681972566;432145;442323;446077;449486;450101;113239.43380283046;113251.43374283076;115409;115861;116918.41540792241;1042.2183362478142;1084.4740300954031;1137.397179494869;1144.3591588710349;1199.412287978884;19927.501812454804;20181.19275229033;20251.29120480776;20279.59440811146;20354.694679580116;58293.4170658288;58444.4155558456;58961.70519147377;59565.106523401315;59979.40020599738;72554.63722681353;73486.63256683682;73490.63254683692;73619.63190184014;74264.62867685627;13315.866841331463;13854.099483533539;14253.287335633384;14269.571912842632;14305.785413219019;22210.55578888381;22298.77701222967;22403.21588744386;22540.549189016456;22546.887265563568;18040.2783888643;18076.186571604572;18131.184096715417;18193.090345482407;18222.453326400228;15929.681406371576;16263;16576.254068567185;16669.24988375502;17057.402990895258;18390.356337528123;18581.535461613566;18588.721169182743;18692.906535467235;18781.624367512846;13283.66790830237;14142.151470911778;14167.079139855876;14187.64530886736;14206.21865797391;18163.636727265115;18164.81835181684;18172.818271817112;18175.363862264756;18181 23:05:05 INFO - PID 22862 | |3;mootools.html;1347.110906801511;1398.7761958086708;1408.9506867259636;1412.7245187188496;1412.795144704018;14900.180490073042;15543.455979040737;15551.222438878054;15569.221538923066;15570.922145389286;15324.46364377247;15461.536153915373;15465.149416782073;15465.458708945162;15498.845011549882;2654.1506717850298;2686.6507354043997;2714.8642567871625;2717.7418145276165;2744.423671029086;2706.039356028611;2735.7948153888537;2746.2722378569697;2747.7527022567965;2751.5322395192866;2638.4723055388904;2665.8533780642156;2848.51575232211;2849.0170891042662;2849.3446507303333;2788.0520622988283;2800.915972520828;2806.3685670723926;2831.5752637104497;2833.3908209735005;2593.5072336256053;2624.3439140214946;2658.2822637887857;2701.4326991331845;2721.809473336867;14940.32768525408;15235.238238088052;15266.313015914311;15276.618084547976;15277.694446111127;7739.148693643695;8025.036995560512;8025.9598702006115;8029.759107226792;8037.517748935024;5071.239314102914;5230.137027390514;5267.604929630293;5357.383900851374;5378.757955891994;1867.2344338821017;1877.6057028024131;1923.422973108062;1940.6506828770816;1969.6454638165123;26408.73591264082;26585;26601.86699066531;26695.866520667274;26717.465650686878;6732.562383445158;6913.930860691429;7015.333543313377;7089.113860767404;7148.142222933281;16858.24137913797;17318.480445586654;17339.393121240697;17375.56561085983;17459.738103928452;7894.960525197337;8096.5546894921345;8100.392470564613;8108.310793582494;8108.554029528433;7400.259974002664;7487.812804679927;7524.05949256343;7548.886766698504;7551.131619863676;5182.611304152204;5308;5311.282976798083;5335.439778823245;5340.30576025122;1537.45420375765;1539.923003849803;1539.9769003464958;1547.8684311833395;1564.3351575580332;897.5691667999266;902.2240872849344;915.075773468788;919.4575200631659;920.5857364186143;7776.494527855672;7788.260115289038;7868.72459463916;7893.842123157505;7931.444798864024;710.1194518796655;714.7677004973405;719.8380364417964;728.8542291541713;730.8136425211643;4929.852104436801;5357.705326207096;5401.4868587484125;5444.128939369682;5581.6930068846605;20401;21635.67546486804;21644.675329870064;21800.45498862541;21841.344759657237;20659.380218593466;21126.577468450545;21357.03893324804;21372.145114195257;21384.037718302403;10844.186685998582;10915.563377464812;10938.453077346101;10945.945270273756;10966.396848173426;7927.881081783893;8454.661813527637;8517.531535765345;8567.785805354666;8662.91337086621;18173.273069077088;18282.177302021708;18412.723809142604;18519.166637501075;18631.72052419242;18945.14746836368;18966.24135034583;18980.145893434554;19002.809971900104;19060.237590496225;11008.834867477155;11204.887951120385;11241.831372529583;11242.100631949588;11245.156613253876;13994.720105598035;14019.29903504841;14077.507287244896;14204.644883877985;14225.288735563387;11322.94338528302;11336.716582085513;11339;11486.712832179262;11671.708207294885;10441;10499.212559057949;10608.204384671026;10691.30506517074;10715.571377144826;9486.430814151174;9577.090176433228;9584.9041509584;9604.9039509604;9611.759706007406;6776.93223067763;6800.727970881308;6834.350736680008;6868.381845633917;6917.9308206917285;7129.857402852018;7133.464990125657;7148.606826624377;7151.499395042297;7193.784186474414 23:05:05 INFO - PID 22862 | |4;prototype.html;491.83428967778536;494.4594057657498;518.4530320511858;519.4156573854414;527.3829619345365;6109.6945152742355;6182.536309776762;6296.685165741717;6305.464035556972;6335.144755458012;6184.628922264667;6208.348123447041;6245.375462453752;6291.968540157304;6296.590721603104;5126.71803050832;5141.383034035921;5201.661891977028;5255.159174532076;5266.4733526647415;4827.662063655545;4991.101601711691;5003.949960500403;5030.440563425332;5062.190049592066;4949.8020079196785;4960.627952903528;4969.70181789092;4982.327385802906;4994.825181118662;4991.900161996776;4999.475055119215;5019.6988180709095;5032.698038117688;5048.9495105048845;4499.257622492318;4499.865004049884;4505.3917721107555;4538.773061346952;4546.499885012646;6073.119397687311;6098.420650038239;6162;6172.197614310111;6264;5716.685582292972;5721.885562288731;5739.971300143514;5760.683162426065;5773.134029895549;6586.506012049115;6606.900896486509;6623.549286763928;6630.403263706241;6659.966700166517;4854.417469903598;4875.659193721719;4894.388201474816;4917.926231106536;4934.679245849008;6817.6023915097285;6926.549774264657;6933.826654333631;6994.6152961587095;7046.260142684995;4997.600191984632;5014.573761230264;5034.823781167642;5047.16721740916;5065.9746701266995;5072.391313042459;5118.97440512795;5126.615503837227;5130;5151.4333423323405;5685.800996965087;5697;5704.11586204139;5737.454941780524;5748.856278592985;6250.531210159256;6306.779262725782;6494.960806270974;6680.899786503206;6710;1741.259964515076;1747.2224859937205;1750.903700296496;1751.7722696049432;1754.894706317625;1017.877854657431;1160.4023927677254;1177.3289225141586;1192.9800020981936;1198.9389390389395;5588.608797384225;5711.828645140652;5714.771409143587;5754.367019627837;5770.826875193751;6763.458923286125;6766.25571187169;6845.212800527904;6860.451163906875;6860.55928255062;5648.85877853057;5700.857478562986;5752.971235143881;5769.365369809318;5771.249737534177;3512.5960514540643;3558.5551806024246;3568.4290513517712;3573.6962358199844;3597.5323207982797;6447.2263328400895;6506.089147519351;6528.902066469007;6552.541322107407;6557.868842623121;6916.688749006306;6940.33431976322;6984.580925144406;6991.405730512964;7062.6821793019435;6415.075477356868;6435.710393032231;6523.575967562093;6580.243272023683;6585.143931288998;6564.671766411756;6624.470042396693;6721.663916804238;6775.207197845771;6791.592504449749;6500.804975850732;6609.339066093352;6609.356257497039;6659.966700166468;6759.831004224934;6377.266614339411;6488.545801793829;6573.539852210491;6575.506836987341;6588.341165883565;4953;4963;4992.550670439678;5003.699778013331;5017.12200364942;6688.598684078971;6730.226024007302;6751.219317182394;6780.25417204117;6842.863142737217;6347.333529979285;6508.381703738138;6531.412172904462;6613.10723052401;6615.305392933865;6462.224533056064;6462.676866156768;6532.346765323429;6567.9671601641685;6622.106015688013;6297.937830099651;6314.242290924934;6337.04944258365;6452.193475815523;6460.741570337133;6071.332153463027;6454.548181627241;6524.967375163284;6526.869462610626;6528.37980391862;6172.4136207060255;6279.9590133205975;6344.460720838781;6357.841053973688;6377.489800815831 23:05:05 INFO - PID 22862 | |5;yui.html;891.1801142948486;939.7368736753707;943.377370935183;945.6501094595001;951.4529145741199;19763.011849407525;19816.306429274977;20317.085731142117;20341.593168136627;20345;18566.071696415158;18666;18668.159932803024;18776.155073021713;18823.341183058597;971.6647756524005;984.8473486609587;987.6345752071744;987.9851802222972;989.0307498651326;964.3056998960772;990.0544979544518;999.3654029691126;1001.5142655811925;1006.3760468509498;1035.5288343803588;1046.0219694585548;1047.2616805152388;1048.2138396202847;1048.5176818663424;984.5815528400401;1027.3835698580867;1039.3504059962524;1039.6465201831413;1040.3289878028704;749.1310080306872;755.0938873352;755.5693254844741;757.8711619024779;758.5524540521064;20096.296629617893;20331.085101170334;20334.389968300828;20348.491287717778;20365.592688146302;19560.119794609276;20069.598608027758;20468.897655511777;20500.89749551243;20582.485437864023;16725.66548669032;16992.150392480326;17157.227924743416;17192;17267.222974966156;3499.4575840744724;3523.3834079036324;3539.3629146753574;3540.3804334241663;3549.7515173937836;129920;132314;133421.6644583893;133598;136204;16822.747658785127;16852.15739213059;17035.14824258782;17331.393401231147;17398.21708023142;31878.406079695924;32574.185645359055;32655.836720816245;32869.50695739518;33075;8552.743417697478;8627.6980305689;8639.654413823377;8659.09079546651;8660.393772435995;4285.700000999963;4286.078493123993;4357.063231405263;4375.956240437555;4380.6057454829215;5591.692456914828;5623.381428042912;5661.80183693577;5661.830145095653;5666.093425051972;1001.7645853224486;1034.75165960169;1049.4070849969742;1050.018232952177;1050.0759331787979;657.8190997475637;673.9561928474634;680.1769858471206;684.4319215051495;693.5838496901819;11447.141464390037;11470.827937580943;11706.35615041181;11763.941180294043;11906.642011095042;695.290803380549;697.1111832413714;703.8521910398873;711.5481669140133;719.463999320499;5092.8472145835685;5150.304708864255;5171.250168725589;5214.869628259324;5282.286891269706;119885.40057299832;121735;122876.38561807133;123701.38149309375;125814.3709281466;108265;110088;112791;115747.42126289314;118122;92847.5357623221;95902.52048739712;96790.51604741931;96999;98676.5066174679;9719.46542940145;9803.705888823346;9876.604935802487;10043.949780251052;10101.5454304555;43299.35050974175;44794;44815;45187;46916.296255556874;56596;57016.71491642515;57122;57604.42395576158;59610.40389596049;15311.846881531043;15684.686306273583;15772.60568485797;15773.211339432753;15990.840091598935;16988.06565638902;17100.65798684044;17233.655326893644;17384;17412.39056633012;16488.917555412627;16564.420245291356;16657.08386038731;16754.832451675815;16763.16184190761;13663.248521331423;14093.083949543246;14163.787543186654;14254.429822806971;14337.211453370164;13560.186388816703;13722.794158087438;13739.175649460665;13887.722245555235;13896.235707035805;11058;11188.937050980145;11287.943560282474;11408.201425900108;11423.828642570534;11084.833727494255;11091.667249982513;11152.55389784432;11243.04434123076;11321.773564528829 23:05:05 INFO - PID 22862 | __end_tp_report 23:05:05 INFO - PID 22862 | __start_cc_report 23:05:05 INFO - PID 22862 | _x_x_mozilla_cycle_collect,371 23:05:05 INFO - PID 22862 | __end_cc_report 23:05:05 INFO - PID 22862 | __startTimestamp1505369105568__endTimestamp 23:05:05 INFO - PID 22862 | 23:05:05 INFO - PID 22862 | ------- Summary: start ------- 23:05:05 INFO - PID 22862 | Number of tests: 6 23:05:05 INFO - PID 22862 | 23:05:05 INFO - PID 22862 | [#0] dojo.html Cycles:165 Average:6134.02 Median:6388.27 stddev:728.97 (11.4%) stddev-sans-first:691.92 23:05:05 INFO - PID 22862 | Values: 3124.3 3142.8 3169.1 3169.7 3174.1 6110.8 6114.6 6164.3 6244.1 6262.9 6223.3 6246.8 6265.0 6276.7 6298.7 5731.6 5745.5 5756.0 5777.5 5791.9 5745.1 5775.9 5822.1 5830.7 5832.1 5751.3 5807.6 5824.9 5828.6 5883.9 5509.9 5752.0 5757.6 5819.3 5830.0 5638.8 5664.7 5694.0 5729.2 5784.9 6164.1 6213.5 6215.8 6275.3 6317.9 6497.9 6566.5 6637.8 6671.6 6702.1 6693.7 6748.0 6753.2 6753.8 6785.4 6170.3 6252.8 6256.7 6276.9 6284.2 6821.7 6857.2 6927.2 6938.8 6939.9 6454.5 6526.2 6550.3 6563.0 6577.5 6586.3 6618.9 6671.2 6702.5 6723.6 6603.4 6605.9 6609.0 6686.2 6708.2 6556.3 6649.8 6657.0 6711.3 6722.6 5200.3 5221.9 5229.3 5230.9 5246.9 4519.6 4599.8 4605.1 4637.3 4650.0 5536.3 5589.6 5683.2 5752.0 5757.4 6649.2 6724.1 6732.5 6732.8 6756.3 5623.9 5686.4 5696.4 5755.7 5761.8 5722.6 5762.9 5772.0 5796.4 5799.4 6411.7 6469.5 6492.3 6500.4 6510.8 6603.8 6629.9 6721.4 6722.6 6735.2 6390.8 6433.0 6463.0 6487.9 6501.9 6510.3 6607.2 6627.1 6627.8 6643.7 6623.2 6651.1 6665.2 6711.4 6729.1 6388.3 6397.3 6397.5 6474.6 6520.3 6477.7 6491.4 6503.0 6506.3 6529.8 6366.5 6369.8 6447.8 6456.4 6456.7 6502.4 6509.2 6537.9 6563.4 6580.4 6227.8 6246.0 6304.0 6332.3 6345.6 23:05:05 INFO - PID 22862 | 23:05:05 INFO - PID 22862 | [#1] ext.html Cycles:180 Average:36134.75 Median:34467.90 stddev:50462.74 (146.4%) stddev-sans-first:50534.73 23:05:05 INFO - PID 22862 | Values: 844.7 861.4 863.9 891.3 908.8 40338.2 41081.8 41186.0 41237.2 41291.2 37033.6 37131.8 37347.8 37421.0 37449.1 6856.4 6963.5 6964.7 6980.1 6996.3 6951.9 6977.1 6982.4 7027.0 7031.6 6946.3 6958.6 6973.2 7578.7 7788.1 7695.2 7709.7 7712.6 7720.2 7755.8 7635.3 7665.2 7712.0 7717.5 7718.8 37769.8 38474.2 38503.2 38516.4 38655.6 3485.2 3531.6 3531.7 3532.1 3534.8 30835.8 30967.4 30984.1 31037.7 31070.4 5194.1 5196.6 5230.0 5230.7 5263.2 187193.0 187246.0 187561.0 187615.0 187723.0 47247.5 47318.5 47341.0 47412.8 47629.8 48823.0 48840.0 48841.5 48949.3 49015.5 16020.4 16219.9 16225.5 16268.8 16278.7 13285.3 13317.7 13358.3 13365.3 13372.3 13938.1 14151.0 14194.8 14241.7 14301.6 5666.3 5736.7 5761.2 5805.6 5984.7 1005.8 1016.7 1022.5 1027.9 1032.3 22493.0 22511.0 22682.2 22741.8 22751.5 1105.9 1109.7 1110.0 1121.6 1131.8 12002.6 12013.7 12049.1 12087.2 12093.2 208549.0 210507.0 210878.0 211070.0 211425.0 44019.3 44784.0 51242.5 51339.5 51515.7 1458.2 1467.2 1468.0 1477.5 1493.8 26046.2 26559.0 26602.9 26877.7 27300.7 36063.5 36216.3 36269.8 36275.3 36377.1 186455.0 186522.0 187443.0 187478.0 187671.0 33416.8 33424.3 33433.0 33450.0 33672.5 42109.8 42161.9 42590.8 42870.0 42934.4 30741.0 30764.0 31294.0 31302.5 31684.2 29721.4 31097.8 31320.8 31393.7 31402.0 34405.1 34530.7 34537.7 34673.7 34750.5 30372.0 30551.5 30705.2 30801.7 31111.5 22734.9 22772.3 22835.4 22854.9 23169.8 23:05:05 INFO - PID 22862 | 23:05:05 INFO - PID 22862 | [#2] jquery.html Cycles:180 Average:33334.30 Median:22565.33 stddev:75430.20 (334.3%) stddev-sans-first:75601.65 23:05:05 INFO - PID 22862 | Values: 557.4 568.5 572.5 573.9 583.3 22583.8 23219.4 23401.2 23433.8 23469.4 23651.0 24733.6 24831.5 24885.0 25059.5 6598.8 6606.8 6631.2 6631.8 6638.6 6180.2 6566.0 6652.1 6654.3 6657.0 6436.5 6448.5 6450.7 6480.5 6481.0 6400.7 6404.4 6405.0 6458.1 6480.0 5879.4 5972.1 6102.7 6111.5 6133.1 28381.7 28818.1 28824.0 28892.1 28895.7 2053.1 2075.9 2076.3 2076.9 2082.1 18553.2 19005.0 19185.8 19271.1 19292.1 2393.3 2418.1 2431.0 2434.8 2440.3 125012.0 128895.0 129842.0 138575.3 143718.0 14480.7 14482.4 14682.9 14701.8 14862.0 22054.1 22437.2 22500.5 22504.9 22543.4 9805.3 9874.2 9977.6 10033.8 10045.8 9167.4 9184.4 9194.3 9249.4 9353.2 8724.4 8779.3 8838.4 9052.3 9143.3 2562.2 2607.4 2609.2 2680.2 2680.2 938.6 953.6 958.8 974.7 974.9 18840.6 18939.6 19176.9 19261.7 19559.0 997.1 1013.0 1021.7 1028.8 1030.9 10321.2 10370.1 10527.2 10566.3 10569.4 432145.0 442323.0 446077.0 449486.0 450101.0 113239.4 113251.4 115409.0 115861.0 116918.4 1042.2 1084.5 1137.4 1144.4 1199.4 19927.5 20181.2 20251.3 20279.6 20354.7 58293.4 58444.4 58961.7 59565.1 59979.4 72554.6 73486.6 73490.6 73619.6 74264.6 13315.9 13854.1 14253.3 14269.6 14305.8 22210.6 22298.8 22403.2 22540.5 22546.9 18040.3 18076.2 18131.2 18193.1 18222.5 15929.7 16263.0 16576.3 16669.2 17057.4 18390.4 18581.5 18588.7 18692.9 18781.6 13283.7 14142.2 14167.1 14187.6 14206.2 18163.6 18164.8 18172.8 18175.4 18181.0 23:05:05 INFO - PID 22862 | 23:05:05 INFO - PID 22862 | [#3] mootools.html Cycles:180 Average:9295.77 Median:14702.06 stddev:6654.20 (45.3%) stddev-sans-first:6646.06 23:05:05 INFO - PID 22862 | Values: 1347.1 1398.8 1409.0 1412.7 1412.8 14900.2 15543.5 15551.2 15569.2 15570.9 15324.5 15461.5 15465.1 15465.5 15498.8 2654.2 2686.7 2714.9 2717.7 2744.4 2706.0 2735.8 2746.3 2747.8 2751.5 2638.5 2665.9 2848.5 2849.0 2849.3 2788.1 2800.9 2806.4 2831.6 2833.4 2593.5 2624.3 2658.3 2701.4 2721.8 14940.3 15235.2 15266.3 15276.6 15277.7 7739.1 8025.0 8026.0 8029.8 8037.5 5071.2 5230.1 5267.6 5357.4 5378.8 1867.2 1877.6 1923.4 1940.7 1969.6 26408.7 26585.0 26601.9 26695.9 26717.5 6732.6 6913.9 7015.3 7089.1 7148.1 16858.2 17318.5 17339.4 17375.6 17459.7 7895.0 8096.6 8100.4 8108.3 8108.6 7400.3 7487.8 7524.1 7548.9 7551.1 5182.6 5308.0 5311.3 5335.4 5340.3 1537.5 1539.9 1540.0 1547.9 1564.3 897.6 902.2 915.1 919.5 920.6 7776.5 7788.3 7868.7 7893.8 7931.4 710.1 714.8 719.8 728.9 730.8 4929.9 5357.7 5401.5 5444.1 5581.7 20401.0 21635.7 21644.7 21800.5 21841.3 20659.4 21126.6 21357.0 21372.1 21384.0 10844.2 10915.6 10938.5 10945.9 10966.4 7927.9 8454.7 8517.5 8567.8 8662.9 18173.3 18282.2 18412.7 18519.2 18631.7 18945.1 18966.2 18980.1 19002.8 19060.2 11008.8 11204.9 11241.8 11242.1 11245.2 13994.7 14019.3 14077.5 14204.6 14225.3 11322.9 11336.7 11339.0 11486.7 11671.7 10441.0 10499.2 10608.2 10691.3 10715.6 9486.4 9577.1 9584.9 9604.9 9611.8 6776.9 6800.7 6834.4 6868.4 6917.9 7129.9 7133.5 7148.6 7151.5 7193.8 23:05:05 INFO - PID 22862 | 23:05:05 INFO - PID 22862 | [#4] prototype.html Cycles:180 Average:5524.08 Median:6172.31 stddev:1553.16 (25.2%) stddev-sans-first:1510.89 23:05:05 INFO - PID 22862 | Values: 491.8 494.5 518.5 519.4 527.4 6109.7 6182.5 6296.7 6305.5 6335.1 6184.6 6208.3 6245.4 6292.0 6296.6 5126.7 5141.4 5201.7 5255.2 5266.5 4827.7 4991.1 5003.9 5030.4 5062.2 4949.8 4960.6 4969.7 4982.3 4994.8 4991.9 4999.5 5019.7 5032.7 5048.9 4499.3 4499.9 4505.4 4538.8 4546.5 6073.1 6098.4 6162.0 6172.2 6264.0 5716.7 5721.9 5740.0 5760.7 5773.1 6586.5 6606.9 6623.5 6630.4 6660.0 4854.4 4875.7 4894.4 4917.9 4934.7 6817.6 6926.5 6933.8 6994.6 7046.3 4997.6 5014.6 5034.8 5047.2 5066.0 5072.4 5119.0 5126.6 5130.0 5151.4 5685.8 5697.0 5704.1 5737.5 5748.9 6250.5 6306.8 6495.0 6680.9 6710.0 1741.3 1747.2 1750.9 1751.8 1754.9 1017.9 1160.4 1177.3 1193.0 1198.9 5588.6 5711.8 5714.8 5754.4 5770.8 6763.5 6766.3 6845.2 6860.5 6860.6 5648.9 5700.9 5753.0 5769.4 5771.2 3512.6 3558.6 3568.4 3573.7 3597.5 6447.2 6506.1 6528.9 6552.5 6557.9 6916.7 6940.3 6984.6 6991.4 7062.7 6415.1 6435.7 6523.6 6580.2 6585.1 6564.7 6624.5 6721.7 6775.2 6791.6 6500.8 6609.3 6609.4 6660.0 6759.8 6377.3 6488.5 6573.5 6575.5 6588.3 4953.0 4963.0 4992.6 5003.7 5017.1 6688.6 6730.2 6751.2 6780.3 6842.9 6347.3 6508.4 6531.4 6613.1 6615.3 6462.2 6462.7 6532.3 6568.0 6622.1 6297.9 6314.2 6337.0 6452.2 6460.7 6071.3 6454.5 6525.0 6526.9 6528.4 6172.4 6280.0 6344.5 6357.8 6377.5 23:05:05 INFO - PID 22862 | 23:05:05 INFO - PID 22862 | [#5] yui.html Cycles:180 Average:24188.40 Median:19942.95 stddev:35146.29 (176.2%) stddev-sans-first:35201.35 23:05:05 INFO - PID 22862 | Values: 891.2 939.7 943.4 945.7 951.5 19763.0 19816.3 20317.1 20341.6 20345.0 18566.1 18666.0 18668.2 18776.2 18823.3 971.7 984.8 987.6 988.0 989.0 964.3 990.1 999.4 1001.5 1006.4 1035.5 1046.0 1047.3 1048.2 1048.5 984.6 1027.4 1039.4 1039.6 1040.3 749.1 755.1 755.6 757.9 758.6 20096.3 20331.1 20334.4 20348.5 20365.6 19560.1 20069.6 20468.9 20500.9 20582.5 16725.7 16992.2 17157.2 17192.0 17267.2 3499.5 3523.4 3539.4 3540.4 3549.8 129920.0 132314.0 133421.7 133598.0 136204.0 16822.7 16852.2 17035.1 17331.4 17398.2 31878.4 32574.2 32655.8 32869.5 33075.0 8552.7 8627.7 8639.7 8659.1 8660.4 4285.7 4286.1 4357.1 4376.0 4380.6 5591.7 5623.4 5661.8 5661.8 5666.1 1001.8 1034.8 1049.4 1050.0 1050.1 657.8 674.0 680.2 684.4 693.6 11447.1 11470.8 11706.4 11763.9 11906.6 695.3 697.1 703.9 711.5 719.5 5092.8 5150.3 5171.3 5214.9 5282.3 119885.4 121735.0 122876.4 123701.4 125814.4 108265.0 110088.0 112791.0 115747.4 118122.0 92847.5 95902.5 96790.5 96999.0 98676.5 9719.5 9803.7 9876.6 10043.9 10101.5 43299.4 44794.0 44815.0 45187.0 46916.3 56596.0 57016.7 57122.0 57604.4 59610.4 15311.8 15684.7 15772.6 15773.2 15990.8 16988.1 17100.7 17233.7 17384.0 17412.4 16488.9 16564.4 16657.1 16754.8 16763.2 13663.2 14093.1 14163.8 14254.4 14337.2 13560.2 13722.8 13739.2 13887.7 13896.2 11058.0 11188.9 11287.9 11408.2 11423.8 11084.8 11091.7 11152.6 11243.0 11321.8 23:05:05 INFO - PID 22862 | -------- Summary: end -------- 23:05:05 INFO - PID 22862 | 23:05:06 INFO - TEST-INFO | 22862: exit 0 23:05:06 INFO - TEST-OK | dromaeo_css | took 1108985ms 23:05:06 INFO - TEST-START | kraken 23:05:06 INFO - Initialising browser for kraken test... 23:05:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50669/getInfo.html -profile /tmp/tmpAI4rOc/profile 23:05:06 INFO - TEST-INFO | started process 23230 (/builds/slave/test/build/application/firefox/firefox http://localhost:50669/getInfo.html) 23:05:11 INFO - TEST-INFO | 23230: exit 0 23:05:11 INFO - Browser initialized. 23:05:11 INFO - Running cycle 1/1 for kraken test... 23:05:11 INFO - Using env: {'DISPLAY': ':0', 23:05:11 INFO - 'HOME': '/home/cltbld', 23:05:11 INFO - 'JSGC_DISABLE_POISONING': '1', 23:05:11 INFO - 'LANG': 'en_US.UTF-8', 23:05:11 INFO - 'LANGUAGE': 'en_US:en', 23:05:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 23:05:11 INFO - 'LOGNAME': 'cltbld', 23:05:11 INFO - 'MAIL': '/var/mail/cltbld', 23:05:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:05:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:05:11 INFO - 'MOZ_CRASHREPORTER': '1', 23:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 23:05:11 INFO - 'MOZ_NO_REMOTE': '1', 23:05:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:05:11 INFO - 'NO_EM_RESTART': '1', 23:05:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:11 INFO - 'PWD': '/builds/slave/test', 23:05:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:05:11 INFO - 'RUST_BACKTRACE': 'full', 23:05:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 23:05:11 INFO - 'SHELL': '/bin/bash', 23:05:11 INFO - 'SHLVL': '1', 23:05:11 INFO - 'TERM': 'linux', 23:05:11 INFO - 'TMOUT': '86400', 23:05:11 INFO - 'USER': 'cltbld', 23:05:11 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290', 23:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:11 INFO - '_': '/tools/buildbot/bin/python'} 23:05:11 INFO - TEST-INFO | started process 23461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAI4rOc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 23:05:12 INFO - PID 23461 | 23:05:12 INFO - PID 23461 | (/builds/slave/test/build/application/firefox/firefox:23511): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 23:05:12 INFO - PID 23461 | 23:05:22 INFO - PID 23461 | RSS: Main: 176599040 23:05:22 INFO - PID 23461 | 23:05:25 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50669/tests/kraken/driver.html?testName=audio-beat-detection) 23:05:26 INFO - PID 23461 | RSS: Main: 181096448 23:05:26 INFO - PID 23461 | 23:05:29 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50669/tests/kraken/driver.html?testName=audio-dft) 23:05:29 INFO - PID 23461 | RSS: Main: 181493760 23:05:29 INFO - PID 23461 | 23:05:32 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50669/tests/kraken/driver.html?testName=audio-fft) 23:05:33 INFO - PID 23461 | RSS: Main: 177803264 23:05:33 INFO - PID 23461 | 23:05:35 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50669/tests/kraken/driver.html?testName=audio-oscillator) 23:05:36 INFO - PID 23461 | RSS: Main: 177823744 23:05:36 INFO - PID 23461 | 23:05:38 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50669/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:05:38 INFO - PID 23461 | RSS: Main: 178016256 23:05:38 INFO - PID 23461 | 23:05:42 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50669/tests/kraken/driver.html?testName=imaging-darkroom) 23:05:42 INFO - PID 23461 | RSS: Main: 180854784 23:05:42 INFO - PID 23461 | 23:05:46 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50669/tests/kraken/driver.html?testName=imaging-desaturate) 23:05:46 INFO - PID 23461 | RSS: Main: 179888128 23:05:46 INFO - PID 23461 | 23:05:49 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50669/tests/kraken/driver.html?testName=json-parse-financial) 23:05:50 INFO - PID 23461 | RSS: Main: 180801536 23:05:50 INFO - PID 23461 | 23:05:52 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50669/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:05:52 INFO - PID 23461 | RSS: Main: 181166080 23:05:52 INFO - PID 23461 | 23:05:54 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50669/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:05:55 INFO - PID 23461 | RSS: Main: 180109312 23:05:55 INFO - PID 23461 | 23:05:57 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50669/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:05:57 INFO - PID 23461 | RSS: Main: 177721344 23:05:57 INFO - PID 23461 | 23:06:00 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:06:01 INFO - PID 23461 | RSS: Main: 178319360 23:06:01 INFO - PID 23461 | 23:06:04 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:06:04 INFO - PID 23461 | RSS: Main: 178401280 23:06:04 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | Cycle 1(1): loaded http://localhost:50669/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50669/tests/kraken/driver.html?testName=ai-astar) 23:06:06 INFO - PID 23461 | RSS: Main: 179830784 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | __start_tp_report 23:06:06 INFO - PID 23461 | _x_x_mozilla_page_load 23:06:06 INFO - PID 23461 | _x_x_mozilla_page_load_details 23:06:06 INFO - PID 23461 | |i|pagename|runs| 23:06:06 INFO - PID 23461 | |0;ai-astar;109;107;108;110;107;100;108;107;109;107 23:06:06 INFO - PID 23461 | |1;audio-beat-detection;115;116;115;118;123;116;119;123;139;115 23:06:06 INFO - PID 23461 | |2;audio-dft;165;162;163;162;162;164;166;165;173;169 23:06:06 INFO - PID 23461 | |3;audio-fft;74;74;77;74;74;74;75;73;75;76 23:06:06 INFO - PID 23461 | |4;audio-oscillator;79;81;79;80;78;77;80;80;81;77 23:06:06 INFO - PID 23461 | |5;imaging-gaussian-blur;114;114;114;114;115;114;110;113;115;116 23:06:06 INFO - PID 23461 | |6;imaging-darkroom;148;148;148;148;147;148;147;148;148;147 23:06:06 INFO - PID 23461 | |7;imaging-desaturate;94;94;93;94;94;95;93;93;93;96 23:06:06 INFO - PID 23461 | |8;json-parse-financial;52;52;53;54;55;53;54;54;54;52 23:06:06 INFO - PID 23461 | |9;json-stringify-tinderbox;38;38;38;37;39;37;38;40;38;38 23:06:06 INFO - PID 23461 | |10;stanford-crypto-aes;76;76;73;77;75;74;75;77;75;78 23:06:06 INFO - PID 23461 | |11;stanford-crypto-ccm;112;112;111;116;113;120;114;107;108;112 23:06:06 INFO - PID 23461 | |12;stanford-crypto-pbkdf2;177;172;178;174;180;177;175;168;166;169 23:06:06 INFO - PID 23461 | |13;stanford-crypto-sha256-iterative;67;64;63;64;65;66;69;68;65;65 23:06:06 INFO - PID 23461 | __end_tp_report 23:06:06 INFO - PID 23461 | __start_cc_report 23:06:06 INFO - PID 23461 | _x_x_mozilla_cycle_collect,956 23:06:06 INFO - PID 23461 | __end_cc_report 23:06:06 INFO - PID 23461 | __startTimestamp1505369166750__endTimestamp 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | ------- Summary: start ------- 23:06:06 INFO - PID 23461 | Number of tests: 14 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#0] ai-astar Cycles:10 Average:107.20 Median:108.00 stddev:2.74 (2.5%) stddev-sans-first:2.83 23:06:06 INFO - PID 23461 | Values: 109.0 107.0 108.0 110.0 107.0 100.0 108.0 107.0 109.0 107.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#1] audio-beat-detection Cycles:10 Average:119.90 Median:118.50 stddev:7.39 (6.2%) stddev-sans-first:7.62 23:06:06 INFO - PID 23461 | Values: 115.0 116.0 115.0 118.0 123.0 116.0 119.0 123.0 139.0 115.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#2] audio-dft Cycles:10 Average:165.10 Median:165.00 stddev:3.54 (2.1%) stddev-sans-first:3.76 23:06:06 INFO - PID 23461 | Values: 165.0 162.0 163.0 162.0 162.0 164.0 166.0 165.0 173.0 169.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#3] audio-fft Cycles:10 Average:74.60 Median:74.50 stddev:1.17 (1.6%) stddev-sans-first:1.22 23:06:06 INFO - PID 23461 | Values: 74.0 74.0 77.0 74.0 74.0 74.0 75.0 73.0 75.0 76.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#4] audio-oscillator Cycles:10 Average:79.20 Median:80.00 stddev:1.48 (1.8%) stddev-sans-first:1.56 23:06:06 INFO - PID 23461 | Values: 79.0 81.0 79.0 80.0 78.0 77.0 80.0 80.0 81.0 77.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#5] imaging-gaussian-blur Cycles:10 Average:113.90 Median:114.00 stddev:1.60 (1.4%) stddev-sans-first:1.69 23:06:06 INFO - PID 23461 | Values: 114.0 114.0 114.0 114.0 115.0 114.0 110.0 113.0 115.0 116.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 23:06:06 INFO - PID 23461 | Values: 148.0 148.0 148.0 148.0 147.0 148.0 147.0 148.0 148.0 147.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.99 (1.1%) stddev-sans-first:1.05 23:06:06 INFO - PID 23461 | Values: 94.0 94.0 93.0 94.0 94.0 95.0 93.0 93.0 93.0 96.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:54.00 stddev:1.06 (2.0%) stddev-sans-first:1.01 23:06:06 INFO - PID 23461 | Values: 52.0 52.0 53.0 54.0 55.0 53.0 54.0 54.0 54.0 52.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#9] json-stringify-tinderbox Cycles:10 Average:38.10 Median:38.00 stddev:0.88 (2.3%) stddev-sans-first:0.93 23:06:06 INFO - PID 23461 | Values: 38.0 38.0 38.0 37.0 39.0 37.0 38.0 40.0 38.0 38.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:76.00 stddev:1.51 (2.0%) stddev-sans-first:1.59 23:06:06 INFO - PID 23461 | Values: 76.0 76.0 73.0 77.0 75.0 74.0 75.0 77.0 75.0 78.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#11] stanford-crypto-ccm Cycles:10 Average:112.50 Median:112.50 stddev:3.72 (3.3%) stddev-sans-first:3.94 23:06:06 INFO - PID 23461 | Values: 112.0 112.0 111.0 116.0 113.0 120.0 114.0 107.0 108.0 112.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:176.00 stddev:4.70 (2.7%) stddev-sans-first:4.82 23:06:06 INFO - PID 23461 | Values: 177.0 172.0 178.0 174.0 180.0 177.0 175.0 168.0 166.0 169.0 23:06:06 INFO - PID 23461 | 23:06:06 INFO - PID 23461 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:1.90 (2.9%) stddev-sans-first:1.94 23:06:06 INFO - PID 23461 | Values: 67.0 64.0 63.0 64.0 65.0 66.0 69.0 68.0 65.0 65.0 23:06:06 INFO - PID 23461 | -------- Summary: end -------- 23:06:06 INFO - PID 23461 | 23:06:07 INFO - PID 23461 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 23:06:07 INFO - TEST-INFO | 23461: exit 0 23:06:07 INFO - TEST-OK | kraken | took 61206ms 23:06:07 INFO - SUITE-END | took 1170s 23:06:07 INFO - Completed test suite (00:19:30) 23:06:07 INFO - javascript benchmark 23:06:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8426.926060672247, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3124.3438877835656, 3142.7800053996216, 3169.0809665197094, 3169.7305729013033, 3174.063651222889, 6110.847228819276, 6114.633122012682, 6164.321924588292, 6244.094606282092, 6262.906056409158, 6223.315435302125, 6246.750129994806, 6264.96867515663, 6276.68616569172, 6298.748050078003, 5731.598788084836, 5745.482906538411, 5755.971220143893, 5777.537796976232, 5791.913121303184, 5745.080787074069, 5775.884482310351, 5822.126680997863, 5830.679312637803, 5832.125181222808, 5751.309842818847, 5807.622504537213, 5824.941750582503, 5828.562857785661, 5883.852903677421, 5509.889802203974, 5752.0, 5757.596968212227, 5819.30168379795, 5830.0380437227595, 5638.830835074954, 5664.716764161775, 5694.0, 5729.197912292282, 5784.855378615526, 6164.075388691687, 6213.471854892339, 6215.81352559424, 6275.341089185661, 6317.87364252717, 6497.870042599169, 6566.540342176049, 6637.767678131242, 6671.5997040177745, 6702.128723265993, 6693.698783554752, 6748.021536877174, 6753.189617245962, 6753.797386078426, 6785.355391237825, 6170.259568851767, 6252.812415627538, 6256.749730010794, 6276.937230627681, 6284.2458904931245, 6821.693023813841, 6857.211420686585, 6927.203371612329, 6938.757143500055, 6939.930600693929, 6454.5159113065765, 6526.151600292027, 6550.2794692583775, 6563.04835798816, 6577.506686998494, 6586.308437614078, 6618.86762264752, 6671.232808227019, 6702.463802895757, 6723.630200339028, 6603.405693487609, 6605.86788264232, 6609.041688955074, 6686.19765628118, 6708.1950165979415, 6556.344365563501, 6649.833754156088, 6657.034729964128, 6711.261761206263, 6722.563033402813, 5200.323957885526, 5221.895562088737, 5229.294045303911, 5230.895382092337, 5246.947530524646, 4519.638428925678, 4599.839005634787, 4605.148047611203, 4637.304404339308, 4649.976750116228, 5536.252605898233, 5589.552835773128, 5683.232763576864, 5752.02215623345, 5757.395473475309, 6649.168853893263, 6724.058631791553, 6732.461403087741, 6732.798016059526, 6756.324367563303, 5623.915641265385, 5686.3744988051285, 5696.430356964271, 5755.65466072037, 5761.798337058183, 5722.570807189478, 5762.9135562966585, 5771.971140144273, 5796.42035796417, 5799.3620701722775, 6411.67941602918, 6469.450096741735, 6492.318306577836, 6500.414962653384, 6510.804675859732, 6603.80188594343, 6629.933700662931, 6721.395074443323, 6722.630255336004, 6735.158105236846, 6390.840228994312, 6433.0, 6463.030545418225, 6487.935120648733, 6501.934980650133, 6510.3489651034515, 6607.174103237096, 6627.072209890715, 6627.801165965029, 6643.70103345341, 6623.205215374186, 6651.06885036105, 6665.20017597872, 6711.395974362331, 6729.1252137221845, 6388.265349484683, 6397.264314603694, 6397.520185986163, 6474.643894585843, 6520.282768895323, 6477.740890364333, 6491.415772580491, 6502.992036234391, 6506.316836732075, 6529.804105876831, 6366.5225108118, 6369.840753981188, 6447.838804029937, 6456.418922296828, 6456.709448074754, 6502.382273683992, 6509.1863517060365, 6537.934620653923, 6563.3764792344655, 6580.440662543546, 6227.813165605039, 6245.96877015612, 6303.9684801575695, 6332.271788744353, 6345.587536810092], "value": 6079.031779655804, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.700131453334, 861.4228466927158, 863.8704194370845, 891.3315013739697, 908.7864351877307, 40338.193236135296, 41081.79459102701, 41186.0, 41237.175256494855, 41291.174176516455, 37033.62966370335, 37131.81434092832, 37347.81326093366, 37421.0, 37449.06377340568, 6856.382925536688, 6963.512554121214, 6964.721411143548, 6980.057692211537, 6996.3353481419435, 6951.89572156418, 6977.092977912865, 6982.3715865572085, 7026.964865175667, 7031.613261270628, 6946.340097690711, 6958.582485050913, 6973.23294437614, 7578.65896034677, 7788.104367997667, 7695.230476952288, 7709.730159444449, 7712.57580833054, 7720.189380115091, 7755.844883102334, 7635.274648908345, 7665.156832748393, 7711.9974403327205, 7717.536947783151, 7718.845623087563, 37769.81115094434, 38474.230515389536, 38503.22993540142, 38516.42225366622, 38655.613443865484, 3485.2158264390564, 3531.593866705336, 3531.7351198660203, 3532.0640030391965, 3534.840932158059, 30835.845820770977, 30967.38065238705, 30984.0704778857, 31037.689623103706, 31070.378592428024, 5194.065068287707, 5196.558292545138, 5230.0, 5230.660007099526, 5263.157894736823, 187193.0, 187246.0, 187561.0, 187615.0, 187723.0, 47247.527524725, 47318.526814732104, 47341.0, 47412.7629361851, 47629.761851190524, 48823.0, 48840.0, 48841.511584883694, 48949.265761013616, 49015.509844901804, 16020.439284624983, 16219.918900405659, 16225.51323460298, 16268.75596866048, 16278.67442651164, 13285.26931018784, 13317.667058323426, 13358.265295408846, 13365.26491043002, 13372.331383430985, 13938.094023888414, 14151.0, 14194.787078193835, 14241.715165696834, 14301.57095287143, 5666.34836993751, 5736.741846616912, 5761.193432919395, 5805.622634528742, 5984.73068711909, 1005.7535903703639, 1016.654337525245, 1022.5143057047843, 1027.9074883260541, 1032.3135115148493, 22493.0, 22511.0, 22682.206122785625, 22741.772582274298, 22751.544969100527, 1105.8728246251624, 1109.7336639206694, 1109.9611513596985, 1121.5850135449937, 1131.7510147767478, 12002.579909703118, 12013.699657508632, 12049.096317776202, 12087.214331068453, 12093.153479256367, 208549.0, 210507.0, 210878.0, 211070.0, 211425.0, 44019.33970990438, 44784.0, 51242.48757512377, 51339.48660513422, 51515.7424212884, 1458.2417143085536, 1467.2370367408887, 1467.9853201468063, 1477.4754961988554, 1493.8282097558701, 26046.218613441626, 26559.0, 26602.866985664947, 26877.731222688304, 27300.72699272982, 36063.45904811377, 36216.27567448689, 36269.81865090658, 36275.27449451049, 36377.09057273589, 186455.0, 186522.0, 187443.0, 187478.0, 187671.0, 33416.832915835264, 33424.33151337008, 33433.0, 33450.0, 33672.49491257682, 42109.78945105255, 42161.94595135024, 42590.78704606457, 42870.0, 42934.35598465963, 30741.0, 30764.0, 31294.0, 31302.530462042632, 31684.207894802814, 29721.405571888874, 31097.844510778206, 31320.843395782875, 31393.686063139077, 31402.0, 34405.139871503416, 34530.65469345274, 34537.65462345345, 34673.653263467044, 34750.47874281837, 30372.0, 30551.54172687456, 30705.232369190948, 30801.691983079883, 31111.533327000565, 22734.886325568266, 22772.31683049511, 22835.40152189331, 22854.88572557193, 23169.768302317436], "value": 16816.178611352396, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [557.3590371073266, 568.5331843842886, 572.493343391099, 573.9024365857804, 583.2825624481889, 22583.77416225837, 23219.419514512145, 23401.180958666406, 23433.765662343372, 23469.413264668372, 23651.0, 24733.628995565083, 24831.50336993259, 24885.0, 25059.49881002379, 6598.769043083493, 6606.7687630932805, 6631.237407698104, 6631.767888123916, 6638.56849304796, 6180.196574445338, 6565.967170164143, 6652.101966234569, 6654.301298363651, 6657.034729964153, 6436.549441539094, 6448.5163612729, 6450.677466126698, 6480.513961452886, 6481.027845823117, 6400.7119679614525, 6404.391582799627, 6405.007223880283, 6458.063580780784, 6479.967600161993, 5879.353271140171, 5972.074328479092, 6102.664353460558, 6111.480524155408, 6133.1413602095745, 28381.716182838114, 28818.135455936146, 28824.0, 28892.133236002952, 28895.711042889514, 2053.1068984991575, 2075.8754474731563, 2076.3044380132674, 2076.9376918692465, 2082.0526660369496, 18553.165107570192, 19005.0, 19185.80814191854, 19271.13279902408, 19292.1318540666, 2393.34183099649, 2418.069043418279, 2430.987845060781, 2434.7565243475688, 2440.3411079008747, 125012.0, 128895.0, 129842.0, 138575.30712346375, 143718.0, 14480.685849428624, 14482.420703171754, 14682.853171468149, 14701.839104592564, 14862.0, 22054.117835286408, 22437.21469748551, 22500.549989000127, 22504.887475562846, 22543.43641408945, 9805.313628046111, 9874.21006319507, 9977.60089596408, 10033.849492257623, 10045.79908401828, 9167.44995300284, 9184.357095003286, 9194.264458843276, 9249.445033298023, 9353.204977576983, 8724.389292749574, 8779.29765618749, 8838.425502342327, 9052.321075919332, 9143.31425143117, 2562.167295628928, 2607.381093742987, 2609.178108895692, 2680.2444398918738, 2680.249530131566, 938.5635679409107, 953.6233187890745, 958.750724811554, 974.6929717139078, 974.907383798538, 18840.623187536174, 18939.621207575772, 19176.904115479334, 19261.711074333896, 19559.0220488975, 997.0926456924165, 1012.9898701013043, 1021.6985989133224, 1028.8096702110133, 1030.9484525773682, 10321.225908056926, 10370.066693997576, 10527.21045921559, 10566.260361774603, 10569.418681972566, 432145.0, 442323.0, 446077.0, 449486.0, 450101.0, 113239.43380283046, 113251.43374283076, 115409.0, 115861.0, 116918.41540792241, 1042.2183362478142, 1084.4740300954031, 1137.397179494869, 1144.3591588710349, 1199.412287978884, 19927.501812454804, 20181.19275229033, 20251.29120480776, 20279.59440811146, 20354.694679580116, 58293.4170658288, 58444.4155558456, 58961.70519147377, 59565.106523401315, 59979.40020599738, 72554.63722681353, 73486.63256683682, 73490.63254683692, 73619.63190184014, 74264.62867685627, 13315.866841331463, 13854.099483533539, 14253.287335633384, 14269.571912842632, 14305.785413219019, 22210.55578888381, 22298.77701222967, 22403.21588744386, 22540.549189016456, 22546.887265563568, 18040.2783888643, 18076.186571604572, 18131.184096715417, 18193.090345482407, 18222.453326400228, 15929.681406371576, 16263.0, 16576.254068567185, 16669.24988375502, 17057.402990895258, 18390.356337528123, 18581.535461613566, 18588.721169182743, 18692.906535467235, 18781.624367512846, 13283.66790830237, 14142.151470911778, 14167.079139855876, 14187.64530886736, 14206.21865797391, 18163.636727265115, 18164.81835181684, 18172.818271817112, 18175.363862264756, 18181.0], "value": 11813.322215405728, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.110906801511, 1398.7761958086708, 1408.9506867259636, 1412.7245187188496, 1412.795144704018, 14900.180490073042, 15543.455979040737, 15551.222438878054, 15569.221538923066, 15570.922145389286, 15324.46364377247, 15461.536153915373, 15465.149416782073, 15465.458708945162, 15498.845011549882, 2654.1506717850298, 2686.6507354043997, 2714.8642567871625, 2717.7418145276165, 2744.423671029086, 2706.039356028611, 2735.7948153888537, 2746.2722378569697, 2747.7527022567965, 2751.5322395192866, 2638.4723055388904, 2665.8533780642156, 2848.51575232211, 2849.0170891042662, 2849.3446507303333, 2788.0520622988283, 2800.915972520828, 2806.3685670723926, 2831.5752637104497, 2833.3908209735005, 2593.5072336256053, 2624.3439140214946, 2658.2822637887857, 2701.4326991331845, 2721.809473336867, 14940.32768525408, 15235.238238088052, 15266.313015914311, 15276.618084547976, 15277.694446111127, 7739.148693643695, 8025.036995560512, 8025.9598702006115, 8029.759107226792, 8037.517748935024, 5071.239314102914, 5230.137027390514, 5267.604929630293, 5357.383900851374, 5378.757955891994, 1867.2344338821017, 1877.6057028024131, 1923.422973108062, 1940.6506828770816, 1969.6454638165123, 26408.73591264082, 26585.0, 26601.86699066531, 26695.866520667274, 26717.465650686878, 6732.562383445158, 6913.930860691429, 7015.333543313377, 7089.113860767404, 7148.142222933281, 16858.24137913797, 17318.480445586654, 17339.393121240697, 17375.56561085983, 17459.738103928452, 7894.960525197337, 8096.5546894921345, 8100.392470564613, 8108.310793582494, 8108.554029528433, 7400.259974002664, 7487.812804679927, 7524.05949256343, 7548.886766698504, 7551.131619863676, 5182.611304152204, 5308.0, 5311.282976798083, 5335.439778823245, 5340.30576025122, 1537.45420375765, 1539.923003849803, 1539.9769003464958, 1547.8684311833395, 1564.3351575580332, 897.5691667999266, 902.2240872849344, 915.075773468788, 919.4575200631659, 920.5857364186143, 7776.494527855672, 7788.260115289038, 7868.72459463916, 7893.842123157505, 7931.444798864024, 710.1194518796655, 714.7677004973405, 719.8380364417964, 728.8542291541713, 730.8136425211643, 4929.852104436801, 5357.705326207096, 5401.4868587484125, 5444.128939369682, 5581.6930068846605, 20401.0, 21635.67546486804, 21644.675329870064, 21800.45498862541, 21841.344759657237, 20659.380218593466, 21126.577468450545, 21357.03893324804, 21372.145114195257, 21384.037718302403, 10844.186685998582, 10915.563377464812, 10938.453077346101, 10945.945270273756, 10966.396848173426, 7927.881081783893, 8454.661813527637, 8517.531535765345, 8567.785805354666, 8662.91337086621, 18173.273069077088, 18282.177302021708, 18412.723809142604, 18519.166637501075, 18631.72052419242, 18945.14746836368, 18966.24135034583, 18980.145893434554, 19002.809971900104, 19060.237590496225, 11008.834867477155, 11204.887951120385, 11241.831372529583, 11242.100631949588, 11245.156613253876, 13994.720105598035, 14019.29903504841, 14077.507287244896, 14204.644883877985, 14225.288735563387, 11322.94338528302, 11336.716582085513, 11339.0, 11486.712832179262, 11671.708207294885, 10441.0, 10499.212559057949, 10608.204384671026, 10691.30506517074, 10715.571377144826, 9486.430814151174, 9577.090176433228, 9584.9041509584, 9604.9039509604, 9611.759706007406, 6776.93223067763, 6800.727970881308, 6834.350736680008, 6868.381845633917, 6917.9308206917285, 7129.857402852018, 7133.464990125657, 7148.606826624377, 7151.499395042297, 7193.784186474414], "value": 6621.079765627287, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.83428967778536, 494.4594057657498, 518.4530320511858, 519.4156573854414, 527.3829619345365, 6109.6945152742355, 6182.536309776762, 6296.685165741717, 6305.464035556972, 6335.144755458012, 6184.628922264667, 6208.348123447041, 6245.375462453752, 6291.968540157304, 6296.590721603104, 5126.71803050832, 5141.383034035921, 5201.661891977028, 5255.159174532076, 5266.4733526647415, 4827.662063655545, 4991.101601711691, 5003.949960500403, 5030.440563425332, 5062.190049592066, 4949.8020079196785, 4960.627952903528, 4969.70181789092, 4982.327385802906, 4994.825181118662, 4991.900161996776, 4999.475055119215, 5019.6988180709095, 5032.698038117688, 5048.9495105048845, 4499.257622492318, 4499.865004049884, 4505.3917721107555, 4538.773061346952, 4546.499885012646, 6073.119397687311, 6098.420650038239, 6162.0, 6172.197614310111, 6264.0, 5716.685582292972, 5721.885562288731, 5739.971300143514, 5760.683162426065, 5773.134029895549, 6586.506012049115, 6606.900896486509, 6623.549286763928, 6630.403263706241, 6659.966700166517, 4854.417469903598, 4875.659193721719, 4894.388201474816, 4917.926231106536, 4934.679245849008, 6817.6023915097285, 6926.549774264657, 6933.826654333631, 6994.6152961587095, 7046.260142684995, 4997.600191984632, 5014.573761230264, 5034.823781167642, 5047.16721740916, 5065.9746701266995, 5072.391313042459, 5118.97440512795, 5126.615503837227, 5130.0, 5151.4333423323405, 5685.800996965087, 5697.0, 5704.11586204139, 5737.454941780524, 5748.856278592985, 6250.531210159256, 6306.779262725782, 6494.960806270974, 6680.899786503206, 6710.0, 1741.259964515076, 1747.2224859937205, 1750.903700296496, 1751.7722696049432, 1754.894706317625, 1017.877854657431, 1160.4023927677254, 1177.3289225141586, 1192.9800020981936, 1198.9389390389395, 5588.608797384225, 5711.828645140652, 5714.771409143587, 5754.367019627837, 5770.826875193751, 6763.458923286125, 6766.25571187169, 6845.212800527904, 6860.451163906875, 6860.55928255062, 5648.85877853057, 5700.857478562986, 5752.971235143881, 5769.365369809318, 5771.249737534177, 3512.5960514540643, 3558.5551806024246, 3568.4290513517712, 3573.6962358199844, 3597.5323207982797, 6447.2263328400895, 6506.089147519351, 6528.902066469007, 6552.541322107407, 6557.868842623121, 6916.688749006306, 6940.33431976322, 6984.580925144406, 6991.405730512964, 7062.6821793019435, 6415.075477356868, 6435.710393032231, 6523.575967562093, 6580.243272023683, 6585.143931288998, 6564.671766411756, 6624.470042396693, 6721.663916804238, 6775.207197845771, 6791.592504449749, 6500.804975850732, 6609.339066093352, 6609.356257497039, 6659.966700166468, 6759.831004224934, 6377.266614339411, 6488.545801793829, 6573.539852210491, 6575.506836987341, 6588.341165883565, 4953.0, 4963.0, 4992.550670439678, 5003.699778013331, 5017.12200364942, 6688.598684078971, 6730.226024007302, 6751.219317182394, 6780.25417204117, 6842.863142737217, 6347.333529979285, 6508.381703738138, 6531.412172904462, 6613.10723052401, 6615.305392933865, 6462.224533056064, 6462.676866156768, 6532.346765323429, 6567.9671601641685, 6622.106015688013, 6297.937830099651, 6314.242290924934, 6337.04944258365, 6452.193475815523, 6460.741570337133, 6071.332153463027, 6454.548181627241, 6524.967375163284, 6526.869462610626, 6528.37980391862, 6172.4136207060255, 6279.9590133205975, 6344.460720838781, 6357.841053973688, 6377.489800815831], "value": 5060.4381756091525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [891.1801142948486, 939.7368736753707, 943.377370935183, 945.6501094595001, 951.4529145741199, 19763.011849407525, 19816.306429274977, 20317.085731142117, 20341.593168136627, 20345.0, 18566.071696415158, 18666.0, 18668.159932803024, 18776.155073021713, 18823.341183058597, 971.6647756524005, 984.8473486609587, 987.6345752071744, 987.9851802222972, 989.0307498651326, 964.3056998960772, 990.0544979544518, 999.3654029691126, 1001.5142655811925, 1006.3760468509498, 1035.5288343803588, 1046.0219694585548, 1047.2616805152388, 1048.2138396202847, 1048.5176818663424, 984.5815528400401, 1027.3835698580867, 1039.3504059962524, 1039.6465201831413, 1040.3289878028704, 749.1310080306872, 755.0938873352, 755.5693254844741, 757.8711619024779, 758.5524540521064, 20096.296629617893, 20331.085101170334, 20334.389968300828, 20348.491287717778, 20365.592688146302, 19560.119794609276, 20069.598608027758, 20468.897655511777, 20500.89749551243, 20582.485437864023, 16725.66548669032, 16992.150392480326, 17157.227924743416, 17192.0, 17267.222974966156, 3499.4575840744724, 3523.3834079036324, 3539.3629146753574, 3540.3804334241663, 3549.7515173937836, 129920.0, 132314.0, 133421.6644583893, 133598.0, 136204.0, 16822.747658785127, 16852.15739213059, 17035.14824258782, 17331.393401231147, 17398.21708023142, 31878.406079695924, 32574.185645359055, 32655.836720816245, 32869.50695739518, 33075.0, 8552.743417697478, 8627.6980305689, 8639.654413823377, 8659.09079546651, 8660.393772435995, 4285.700000999963, 4286.078493123993, 4357.063231405263, 4375.956240437555, 4380.6057454829215, 5591.692456914828, 5623.381428042912, 5661.80183693577, 5661.830145095653, 5666.093425051972, 1001.7645853224486, 1034.75165960169, 1049.4070849969742, 1050.018232952177, 1050.0759331787979, 657.8190997475637, 673.9561928474634, 680.1769858471206, 684.4319215051495, 693.5838496901819, 11447.141464390037, 11470.827937580943, 11706.35615041181, 11763.941180294043, 11906.642011095042, 695.290803380549, 697.1111832413714, 703.8521910398873, 711.5481669140133, 719.463999320499, 5092.8472145835685, 5150.304708864255, 5171.250168725589, 5214.869628259324, 5282.286891269706, 119885.40057299832, 121735.0, 122876.38561807133, 123701.38149309375, 125814.3709281466, 108265.0, 110088.0, 112791.0, 115747.42126289314, 118122.0, 92847.5357623221, 95902.52048739712, 96790.51604741931, 96999.0, 98676.5066174679, 9719.46542940145, 9803.705888823346, 9876.604935802487, 10043.949780251052, 10101.5454304555, 43299.35050974175, 44794.0, 44815.0, 45187.0, 46916.296255556874, 56596.0, 57016.71491642515, 57122.0, 57604.42395576158, 59610.40389596049, 15311.846881531043, 15684.686306273583, 15772.60568485797, 15773.211339432753, 15990.840091598935, 16988.06565638902, 17100.65798684044, 17233.655326893644, 17384.0, 17412.39056633012, 16488.917555412627, 16564.420245291356, 16657.08386038731, 16754.832451675815, 16763.16184190761, 13663.248521331423, 14093.083949543246, 14163.787543186654, 14254.429822806971, 14337.211453370164, 13560.186388816703, 13722.794158087438, 13739.175649460665, 13887.722245555235, 13896.235707035805, 11058.0, 11188.937050980145, 11287.943560282474, 11408.201425900108, 11423.828642570534, 11084.833727494255, 11091.667249982513, 11152.55389784432, 11243.04434123076, 11321.773564528829], "value": 8849.914786106487, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1420.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 107.0, 108.0, 110.0, 107.0, 100.0, 108.0, 107.0, 109.0, 107.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 115.0, 118.0, 123.0, 116.0, 119.0, 123.0, 139.0, 115.0], "value": 119.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 163.0, 162.0, 162.0, 164.0, 166.0, 165.0, 173.0, 169.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 77.0, 74.0, 74.0, 74.0, 75.0, 73.0, 75.0, 76.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 79.0, 80.0, 78.0, 77.0, 80.0, 80.0, 81.0, 77.0], "value": 79.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 115.0, 114.0, 110.0, 113.0, 115.0, 116.0], "value": 113.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 148.0, 147.0, 148.0, 147.0, 148.0, 148.0, 147.0], "value": 147.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 94.0, 95.0, 93.0, 93.0, 93.0, 96.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 53.0, 54.0, 55.0, 53.0, 54.0, 54.0, 54.0, 52.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 37.0, 39.0, 37.0, 38.0, 40.0, 38.0, 38.0], "value": 38.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 73.0, 77.0, 75.0, 74.0, 75.0, 77.0, 75.0, 78.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 111.0, 116.0, 113.0, 120.0, 114.0, 107.0, 108.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 172.0, 178.0, 174.0, 180.0, 177.0, 175.0, 168.0, 166.0, 169.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 63.0, 64.0, 65.0, 66.0, 69.0, 68.0, 65.0, 65.0], "value": 65.6, "unit": "score"}]}]} 23:06:07 INFO - Return code: 0 23:06:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:06:08 INFO - # TBPL SUCCESS # 23:06:08 INFO - Running post-action listener: _package_coverage_data 23:06:08 INFO - Running post-action listener: _resource_record_post_action 23:06:08 INFO - [mozharness: 2017-09-14 06:06:08.049250Z] Finished run-tests step (success) 23:06:08 INFO - Running post-run listener: _resource_record_post_run 23:06:08 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 332275712; Read time: 8; Write time: 267028 23:06:08 INFO - TinderboxPrint: CPU usage
13.1% 23:06:08 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:06:08 INFO - TinderboxPrint: I/O write bytes / time
332,275,712 / 267,028 23:06:08 INFO - TinderboxPrint: CPU idle
8,236.0 (86.9%) 23:06:08 INFO - TinderboxPrint: CPU user
1,193.3 (12.6%) 23:06:08 INFO - TinderboxPrint: Swap in / out
0 / 0 23:06:08 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10485760; Read time: 0; Write time: 704 23:06:08 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:08 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 0; Write bytes: 321789952; Read time: 0; Write time: 266324 23:06:08 INFO - Running post-run listener: _upload_blobber_files 23:06:08 INFO - Blob upload gear active. 23:06:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:06:08 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:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:06:09 INFO - (blobuploader) - INFO - Open directory for files ... 23:06:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:06:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:06:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:10 INFO - (blobuploader) - INFO - Done attempting. 23:06:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:06:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:06:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:11 INFO - (blobuploader) - INFO - Done attempting. 23:06:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:06:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:06:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:18 INFO - (blobuploader) - INFO - Done attempting. 23:06:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:06:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:06:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:19 INFO - (blobuploader) - INFO - Done attempting. 23:06:19 INFO - (blobuploader) - INFO - Iteration through files over. 23:06:19 INFO - Return code: 0 23:06:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:06:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:06:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f18ee246bc7cf7f0ca21f605a55bcf3fac13a9849c1d925c59f0a25bddafb029154d31800fca98b22fc72406d25a63acc6e0e726c2fd9c1a86fa09e8569ee7d4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bfda2c0a7e335eb9ea3748c7ad869b022b0cc31040b925b7e6f7978e62254844046c0add20c5f96eb4f30382aff26eda5922bb0b6637e7092aa93e43185a256d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/545e920d0a7c4cf0e9c28308af73723c25abffcb89c2031ba36515da9dbb2fa92572bf5bb482b37234084d2b7079b495f44631fe8e89f0373f403e47981e7140", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5056e75204c5a77710a823898da07fe1ffbf3f4069651ab0d878bd9ba3db629256f7f739e830bb510259f5dd9e3076a0434c889aa92d7b4428b6cec63cae063e"} 23:06:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:06:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:06:19 INFO - Contents: 23:06:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f18ee246bc7cf7f0ca21f605a55bcf3fac13a9849c1d925c59f0a25bddafb029154d31800fca98b22fc72406d25a63acc6e0e726c2fd9c1a86fa09e8569ee7d4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bfda2c0a7e335eb9ea3748c7ad869b022b0cc31040b925b7e6f7978e62254844046c0add20c5f96eb4f30382aff26eda5922bb0b6637e7092aa93e43185a256d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/545e920d0a7c4cf0e9c28308af73723c25abffcb89c2031ba36515da9dbb2fa92572bf5bb482b37234084d2b7079b495f44631fe8e89f0373f403e47981e7140", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5056e75204c5a77710a823898da07fe1ffbf3f4069651ab0d878bd9ba3db629256f7f739e830bb510259f5dd9e3076a0434c889aa92d7b4428b6cec63cae063e"} 23:06:19 INFO - Running post-run listener: copy_logs_to_upload_dir 23:06:19 INFO - Copying logs to upload dir... 23:06:19 INFO - mkdir: /builds/slave/test/build/upload/logs 23:06:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.211927 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-13 23:06:19.788287) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 23:06:19.796165) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f18ee246bc7cf7f0ca21f605a55bcf3fac13a9849c1d925c59f0a25bddafb029154d31800fca98b22fc72406d25a63acc6e0e726c2fd9c1a86fa09e8569ee7d4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bfda2c0a7e335eb9ea3748c7ad869b022b0cc31040b925b7e6f7978e62254844046c0add20c5f96eb4f30382aff26eda5922bb0b6637e7092aa93e43185a256d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/545e920d0a7c4cf0e9c28308af73723c25abffcb89c2031ba36515da9dbb2fa92572bf5bb482b37234084d2b7079b495f44631fe8e89f0373f403e47981e7140", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5056e75204c5a77710a823898da07fe1ffbf3f4069651ab0d878bd9ba3db629256f7f739e830bb510259f5dd9e3076a0434c889aa92d7b4428b6cec63cae063e"} build_url:https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011432 build_url: 'https://queue.taskcluster.net/v1/task/XkYgQGM7RR6WsMLSikInwA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f18ee246bc7cf7f0ca21f605a55bcf3fac13a9849c1d925c59f0a25bddafb029154d31800fca98b22fc72406d25a63acc6e0e726c2fd9c1a86fa09e8569ee7d4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bfda2c0a7e335eb9ea3748c7ad869b022b0cc31040b925b7e6f7978e62254844046c0add20c5f96eb4f30382aff26eda5922bb0b6637e7092aa93e43185a256d", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/545e920d0a7c4cf0e9c28308af73723c25abffcb89c2031ba36515da9dbb2fa92572bf5bb482b37234084d2b7079b495f44631fe8e89f0373f403e47981e7140", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5056e75204c5a77710a823898da07fe1ffbf3f4069651ab0d878bd9ba3db629256f7f739e830bb510259f5dd9e3076a0434c889aa92d7b4428b6cec63cae063e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 23:06:19.827176) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:06:19.827491) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1505366051.533332-813922290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004605 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 23:06:19.938936) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 23:06:19.939249) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 23:06:19.939538) ========= ========= Total master_lag: 0.25 =========