builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-007 starttime: 1506002606.98 results: success (0) revision: 9caeafcec9982be90820e30bc699e851cb6af907 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:26.980997) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:26.981520) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:26.981835) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:27.000627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:27.001058) ========= 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=923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-21 07:03:27-- 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% 42.0M=0s 2017-09-21 07:03:27 (42.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132738 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:27.152225) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:27.152746) ========= 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=923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016265 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:27.202995) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-21 07:03:27.203372) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9caeafcec9982be90820e30bc699e851cb6af907 --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 9caeafcec9982be90820e30bc699e851cb6af907 --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=923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-21 07:03:27,257 truncating revision to first 12 chars 2017-09-21 07:03:27,257 Setting DEBUG logging. 2017-09-21 07:03:27,257 attempt 1/10 2017-09-21 07:03:27,257 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9caeafcec998?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-21 07:03:27,743 attempt 1/10 2017-09-21 07:03:28,365 current task status: no status available at this point. state: STARTED 2017-09-21 07:03:28,365 sleeping for 10.00s (attempt 1/10) 2017-09-21 07:03:38,375 attempt 2/10 2017-09-21 07:03:38,713 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-09-21 07:03:38,904 unpacking tar archive at: mozilla-central-9caeafcec998/testing/mozharness/ program finished with exit code 0 elapsedTime=11.918341 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2017-09-21 07:03:39.137496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:39.137860) ========= script_repo_revision: 9caeafcec9982be90820e30bc699e851cb6af907 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:39.138247) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:39.138528) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-21 07:03:39.151807) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-21 07:03:39.152246) ========= /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=923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:03:39 INFO - MultiFileLogger online at 20170921 07:03:39 in /builds/slave/test 07:03: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 07:03:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:03:39 INFO - {'append_to_log': False, 07:03:39 INFO - 'base_work_dir': '/builds/slave/test', 07:03:39 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 07:03:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:03:39 INFO - 'branch': 'Firefox-Non-PGO', 07:03:39 INFO - 'buildbot_json_path': 'buildprops.json', 07:03:39 INFO - 'code_coverage': False, 07:03:39 INFO - 'config_files': ('talos/linux_config.py',), 07:03:39 INFO - 'default_actions': ('clobber', 07:03:39 INFO - 'read-buildbot-config', 07:03:39 INFO - 'download-and-extract', 07:03:39 INFO - 'populate-webroot', 07:03:39 INFO - 'create-virtualenv', 07:03:39 INFO - 'install', 07:03:39 INFO - 'setup-mitmproxy', 07:03:39 INFO - 'run-tests'), 07:03:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:03:39 INFO - 'disable_ccov_upload': False, 07:03:39 INFO - 'disable_stylo': False, 07:03:39 INFO - 'download_minidump_stackwalk': True, 07:03:39 INFO - 'download_symbols': 'ondemand', 07:03:39 INFO - 'enable_stylo': False, 07:03:39 INFO - 'enable_webrender': False, 07:03:39 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 07:03:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:03:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:03:39 INFO - 'gecko_profile': False, 07:03:39 INFO - 'gecko_profile_interval': 0, 07:03:39 INFO - 'installer_path': 'installer.exe', 07:03:39 INFO - 'log_level': 'info', 07:03:39 INFO - 'log_name': 'talos', 07:03:39 INFO - 'log_to_console': True, 07:03:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:03:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:03:39 INFO - 'opt_config_files': (), 07:03:39 INFO - 'pip_index': False, 07:03:39 INFO - 'suite': 'dromaeojs-e10s', 07:03:39 INFO - 'system_bits': '32', 07:03:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:03:39 INFO - 'title': 'talos-linux64-ix-007', 07:03:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:03:39 INFO - 'use_talos_json': True, 07:03:39 INFO - 'verify': 'False', 07:03:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:03:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:03:39 INFO - 'work_dir': 'build'} 07:03:39 INFO - [mozharness: 2017-09-21 14:03:39.345995Z] Running clobber step. 07:03:39 INFO - Running pre-action listener: _resource_record_pre_action 07:03:39 INFO - Running main action method: clobber 07:03:39 INFO - rmtree: /builds/slave/test/build 07:03:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:03:40 INFO - Running post-action listener: _resource_record_post_action 07:03:40 INFO - [mozharness: 2017-09-21 14:03:40.334643Z] Finished clobber step (success) 07:03:40 INFO - [mozharness: 2017-09-21 14:03:40.334740Z] Running read-buildbot-config step. 07:03:40 INFO - Running pre-action listener: _resource_record_pre_action 07:03:40 INFO - Running main action method: read_buildbot_config 07:03:40 INFO - Using buildbot properties: 07:03:40 INFO - { 07:03:40 INFO - "project": "", 07:03:40 INFO - "product": "firefox", 07:03:40 INFO - "who": "archaeopteryx@coole-files.de", 07:03:40 INFO - "installer_path": "public/build/target.tar.bz2", 07:03:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 07:03:40 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 07:03:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 07:03:40 INFO - "stage_platform": "linux64", 07:03:40 INFO - "basedir": "/builds/slave/test", 07:03:40 INFO - "buildnumber": 1168, 07:03:40 INFO - "platform": "ubuntu64_hw", 07:03:40 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:03:40 INFO - "slavebuilddir": "test", 07:03:40 INFO - "taskId": "bxBRcSwdTM2jwKEIbhRLVA", 07:03:40 INFO - "branch": "mozilla-central", 07:03:40 INFO - "script_repo_revision": "production", 07:03:40 INFO - "revision": "9caeafcec9982be90820e30bc699e851cb6af907", 07:03:40 INFO - "slavename": "talos-linux64-ix-007", 07:03:40 INFO - "repo_path": "mozilla-central" 07:03:40 INFO - } 07:03:40 INFO - Finding installer, test and symbols from parent task. 07:03:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bxBRcSwdTM2jwKEIbhRLVA'}, attempt #1 07:03:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bxBRcSwdTM2jwKEIbhRLVA'}, attempt #1 07:03:41 INFO - Task dependencies: OR_WzdNeS6ee0KU9V4iMiw 07:03:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw'}, attempt #1 07:03:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.tar.bz2 07:03:41 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.test_packages.json 07:03:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.crashreporter-symbols.zip 07:03:41 INFO - Running post-action listener: _resource_record_post_action 07:03:41 INFO - [mozharness: 2017-09-21 14:03:41.989588Z] Finished read-buildbot-config step (success) 07:03:41 INFO - [mozharness: 2017-09-21 14:03:41.989805Z] Running download-and-extract step. 07:03:41 INFO - Running pre-action listener: _resource_record_pre_action 07:03:41 INFO - Running main action method: download_and_extract 07:03:41 INFO - mkdir: /builds/slave/test/build/tests 07:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:41 INFO - trying https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.test_packages.json 07:03:41 INFO - Downloading https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:03:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:03:42 INFO - Downloaded 989 bytes. 07:03:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:03:42 INFO - Using the following test package requirements: 07:03:42 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 07:03:42 INFO - u'common': [u'target.common.tests.zip'], 07:03:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:03:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:03:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:03:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:03:42 INFO - u'mozbase': [u'target.common.tests.zip'], 07:03:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:03:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:03:42 INFO - u'web-platform': [u'target.common.tests.zip', 07:03:42 INFO - u'target.web-platform.tests.tar.gz'], 07:03:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:03:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 07:03:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.common.tests.zip 07:03:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:03:42 INFO - Fetch https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.common.tests.zip into memory 07:03:43 INFO - Content-Length response header: 54741119 07:03:43 INFO - Bytes received: 54741119 07:03:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.talos.tests.zip 07:03:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:03:54 INFO - Fetch https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.talos.tests.zip into memory 07:03:55 INFO - Content-Length response header: 14509184 07:03:55 INFO - Bytes received: 14509184 07:03:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:55 INFO - trying https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.tar.bz2 07:03:55 INFO - Downloading https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:03:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:03:56 INFO - Downloaded 61692717 bytes. 07:03:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.tar.bz2 07:03:56 INFO - mkdir: /builds/slave/test/properties 07:03:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:03:56 INFO - Writing to file /builds/slave/test/properties/build_url 07:03:56 INFO - Contents: 07:03:56 INFO - build_url:https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.tar.bz2 07:03:56 INFO - Running post-action listener: _resource_record_post_action 07:03:56 INFO - Running post-action listener: find_tests_for_verification 07:03:56 INFO - Running post-action listener: set_extra_try_arguments 07:03:56 INFO - [mozharness: 2017-09-21 14:03:56.814865Z] Finished download-and-extract step (success) 07:03:56 INFO - [mozharness: 2017-09-21 14:03:56.815091Z] Running populate-webroot step. 07:03:56 INFO - Running pre-action listener: _resource_record_pre_action 07:03:56 INFO - Running main action method: populate_webroot 07:03:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:03:56 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['tresize', 07:03:56 INFO - 'tcanvasmark']}, 07:03:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:03:56 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['dromaeo_css', 07:03:56 INFO - 'kraken']}, 07:03:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:03:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:03:56 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:03:56 INFO - 'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:03:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:03:56 INFO - 'tests': ['damp', 'tps']}, 07:03:56 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:03:56 INFO - 'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['damp', 'tps']}, 07:03:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:03:56 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['dromaeo_dom']}, 07:03:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:03:56 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['basic_compositor_video', 07:03:56 INFO - 'glvideo']}, 07:03:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:03:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:03:56 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:03:56 INFO - 'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['ts_paint_webext', 07:03:56 INFO - 'tp5o_webext']}, 07:03:56 INFO - 'other-e10s': {'tests': ['a11yr', 07:03:56 INFO - 'ts_paint', 07:03:56 INFO - 'tpaint', 07:03:56 INFO - 'sessionrestore', 07:03:56 INFO - 'sessionrestore_many_windows', 07:03:56 INFO - 'sessionrestore_no_auto_restore', 07:03:56 INFO - 'tabpaint']}, 07:03:56 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['a11yr', 07:03:56 INFO - 'ts_paint', 07:03:56 INFO - 'tpaint', 07:03:56 INFO - 'sessionrestore', 07:03:56 INFO - 'sessionrestore_many_windows', 07:03:56 INFO - 'sessionrestore_no_auto_restore', 07:03:56 INFO - 'tabpaint']}, 07:03:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 07:03:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 07:03:56 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['perf_reftest_singletons']}, 07:03:56 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['bloom_basic']}, 07:03:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:03:56 INFO - 'tsvgr_opacity', 07:03:56 INFO - 'tart', 07:03:56 INFO - 'tscrollx', 07:03:56 INFO - 'tsvg_static']}, 07:03:56 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['tsvgx', 07:03:56 INFO - 'tsvgr_opacity', 07:03:56 INFO - 'tart', 07:03:56 INFO - 'tscrollx', 07:03:56 INFO - 'tsvg_static']}, 07:03:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:03:56 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:03:56 INFO - 'talos_options': ['--disable-stylo'], 07:03:56 INFO - 'tests': ['tp5o']}, 07:03:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:03:56 INFO - 'talos_options': ['--mitmproxy', 07:03:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:03:56 INFO - '--firstNonBlankPaint'], 07:03:56 INFO - 'tests': ['tp6_google', 07:03:56 INFO - 'tp6_youtube', 07:03:56 INFO - 'tp6_amazon', 07:03:56 INFO - 'tp6_facebook']}, 07:03:56 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:03:56 INFO - 'talos_options': ['--disable-stylo', 07:03:56 INFO - '--mitmproxy', 07:03:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:03:56 INFO - '--firstNonBlankPaint'], 07:03:56 INFO - 'tests': ['tp6_google', 07:03:56 INFO - 'tp6_youtube', 07:03:56 INFO - 'tp6_amazon', 07:03:56 INFO - 'tp6_facebook']}, 07:03:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 07:03:56 INFO - 'talos_options': ['--stylo-threads=1', 07:03:56 INFO - '--mitmproxy', 07:03:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 07:03:56 INFO - '--firstNonBlankPaint'], 07:03:56 INFO - 'tests': ['tp6_google', 07:03:56 INFO - 'tp6_youtube', 07:03:56 INFO - 'tp6_amazon', 07:03:56 INFO - 'tp6_facebook']}, 07:03:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:03:56 INFO - 'talos_options': ['--xperf_path', 07:03:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:03:56 INFO - 'tests': ['tp5n']}, 07:03:56 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 07:03:56 INFO - 'talos_options': ['--disable-stylo', 07:03:56 INFO - '--xperf_path', 07:03:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:03:56 INFO - 'tests': ['tp5n']}}} 07:03:56 INFO - Running post-action listener: _resource_record_post_action 07:03:56 INFO - [mozharness: 2017-09-21 14:03:56.830340Z] Finished populate-webroot step (success) 07:03:56 INFO - [mozharness: 2017-09-21 14:03:56.830430Z] Running create-virtualenv step. 07:03:56 INFO - Running pre-action listener: _resource_record_pre_action 07:03:56 INFO - Running main action method: create_virtualenv 07:03:56 INFO - Creating virtualenv /builds/slave/test/build/venv 07:03:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:03:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 07:03:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:03:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:03:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:03:57 INFO - Installing distribute.............................................................................................................................................................................................done. 07:03:57 INFO - Installing pip...............done. 07:03:57 INFO - Return code: 0 07:03:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:03:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:03:57 INFO - Reading from file tmpfile_stdout 07:03:57 INFO - Output received: 07:03:57 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 07:03:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:03:57 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')]} 07:03:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03:57 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')]} 07:03:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03:57 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 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:03:57 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 07:03:57 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 07:03:57 INFO - Using env: {'DISPLAY': ':0', 07:03:57 INFO - 'HOME': '/home/cltbld', 07:03:57 INFO - 'LANG': 'en_US.UTF-8', 07:03:57 INFO - 'LANGUAGE': 'en_US:en', 07:03:57 INFO - 'LOGNAME': 'cltbld', 07:03:57 INFO - 'MAIL': '/var/mail/cltbld', 07:03:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:57 INFO - 'MOZ_NO_REMOTE': '1', 07:03:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:03:57 INFO - 'NO_EM_RESTART': '1', 07:03:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:03:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:57 INFO - 'PWD': '/builds/slave/test', 07:03:57 INFO - 'SHELL': '/bin/bash', 07:03:57 INFO - 'SHLVL': '1', 07:03:57 INFO - 'TERM': 'linux', 07:03:57 INFO - 'TMOUT': '86400', 07:03:57 INFO - 'USER': 'cltbld', 07:03:57 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:03:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:57 INFO - '_': '/tools/buildbot/bin/python'} 07:03:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:03:59 INFO - Downloading/unpacking pip>=1.5 07:03:59 INFO - Running setup.py egg_info for package pip 07:03:59 INFO - warning: no files found matching 'pip/cacert.pem' 07:03:59 INFO - warning: no files found matching '*.html' under directory 'docs' 07:03:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:03:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:03:59 INFO - Installing collected packages: pip 07:03:59 INFO - Found existing installation: pip 1.1 07:03:59 INFO - Uninstalling pip: 07:03:59 INFO - Successfully uninstalled pip 07:03:59 INFO - Running setup.py install for pip 07:03:59 INFO - warning: no files found matching 'pip/cacert.pem' 07:03:59 INFO - warning: no files found matching '*.html' under directory 'docs' 07:03:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 07:03:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 07:03:59 INFO - Installing pip script to /builds/slave/test/build/venv/bin 07:03:59 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 07:03:59 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 07:03:59 INFO - Successfully installed pip 07:03:59 INFO - Cleaning up... 07:03:59 INFO - Return code: 0 07:03:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:03: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')]} 07:03:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:03: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')]} 07:03:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:03: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', '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 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:03: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', 'psutil>=3.1.1'] in /builds/slave/test/build 07:03: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 psutil>=3.1.1 07:03:59 INFO - Using env: {'DISPLAY': ':0', 07:03:59 INFO - 'HOME': '/home/cltbld', 07:03:59 INFO - 'LANG': 'en_US.UTF-8', 07:03:59 INFO - 'LANGUAGE': 'en_US:en', 07:03:59 INFO - 'LOGNAME': 'cltbld', 07:03:59 INFO - 'MAIL': '/var/mail/cltbld', 07:03:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:03:59 INFO - 'MOZ_NO_REMOTE': '1', 07:03:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:03:59 INFO - 'NO_EM_RESTART': '1', 07:03:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:03:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:03:59 INFO - 'PWD': '/builds/slave/test', 07:03:59 INFO - 'SHELL': '/bin/bash', 07:03:59 INFO - 'SHLVL': '1', 07:03:59 INFO - 'TERM': 'linux', 07:03:59 INFO - 'TMOUT': '86400', 07:03:59 INFO - 'USER': 'cltbld', 07:03:59 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:03:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:03:59 INFO - '_': '/tools/buildbot/bin/python'} 07:03:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:03:59 INFO - Downloading/unpacking psutil>=3.1.1 07:03:59 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 07:03:59 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 07:03:59 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 07:03:59 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 07:04:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:04:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:04:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:04:00 INFO - Installing collected packages: psutil 07:04:00 INFO - Running setup.py install for psutil 07:04:00 INFO - building 'psutil._psutil_linux' extension 07:04:00 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 07:04:00 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 07:04:00 INFO - building 'psutil._psutil_posix' extension 07:04:00 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 07:04:00 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 07:04:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:04:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:04:00 INFO - Successfully installed psutil 07:04:00 INFO - Cleaning up... 07:04:01 INFO - Return code: 0 07:04:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:04:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:04:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:04:01 INFO - Using env: {'DISPLAY': ':0', 07:04:01 INFO - 'HOME': '/home/cltbld', 07:04:01 INFO - 'LANG': 'en_US.UTF-8', 07:04:01 INFO - 'LANGUAGE': 'en_US:en', 07:04:01 INFO - 'LOGNAME': 'cltbld', 07:04:01 INFO - 'MAIL': '/var/mail/cltbld', 07:04:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:01 INFO - 'MOZ_NO_REMOTE': '1', 07:04:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:01 INFO - 'NO_EM_RESTART': '1', 07:04:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:01 INFO - 'PWD': '/builds/slave/test', 07:04:01 INFO - 'SHELL': '/bin/bash', 07:04:01 INFO - 'SHLVL': '1', 07:04:01 INFO - 'TERM': 'linux', 07:04:01 INFO - 'TMOUT': '86400', 07:04:01 INFO - 'USER': 'cltbld', 07:04:01 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:01 INFO - '_': '/tools/buildbot/bin/python'} 07:04:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:04:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:04:02 INFO - Installing collected packages: mozsystemmonitor 07:04:02 INFO - Running setup.py install for mozsystemmonitor 07:04:02 INFO - Successfully installed mozsystemmonitor 07:04:02 INFO - Cleaning up... 07:04:03 INFO - Return code: 0 07:04:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:04:03 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')]} 07:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:03 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')]} 07:04:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:03 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 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:04:03 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 07:04:03 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 07:04:03 INFO - Using env: {'DISPLAY': ':0', 07:04:03 INFO - 'HOME': '/home/cltbld', 07:04:03 INFO - 'LANG': 'en_US.UTF-8', 07:04:03 INFO - 'LANGUAGE': 'en_US:en', 07:04:03 INFO - 'LOGNAME': 'cltbld', 07:04:03 INFO - 'MAIL': '/var/mail/cltbld', 07:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:03 INFO - 'MOZ_NO_REMOTE': '1', 07:04:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:03 INFO - 'NO_EM_RESTART': '1', 07:04:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:03 INFO - 'PWD': '/builds/slave/test', 07:04:03 INFO - 'SHELL': '/bin/bash', 07:04:03 INFO - 'SHLVL': '1', 07:04:03 INFO - 'TERM': 'linux', 07:04:03 INFO - 'TMOUT': '86400', 07:04:03 INFO - 'USER': 'cltbld', 07:04:03 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:03 INFO - '_': '/tools/buildbot/bin/python'} 07:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:03 INFO - Downloading/unpacking jsonschema==2.5.1 07:04:03 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 07:04:03 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 07:04:03 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 07:04:03 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 07:04:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:04:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:06 INFO - Downloading functools32-3.2.3-2.tar.gz 07:04:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:04:06 INFO - warning: no files found matching '*.txt' 07:04:06 INFO - no previously-included directories found matching 'build' 07:04:06 INFO - no previously-included directories found matching 'dist' 07:04:06 INFO - no previously-included directories found matching '.git*' 07:04:06 INFO - Installing collected packages: jsonschema, functools32 07:04:06 INFO - Running setup.py install for functools32 07:04:06 INFO - warning: no files found matching '*.txt' 07:04:06 INFO - no previously-included directories found matching 'build' 07:04:06 INFO - no previously-included directories found matching 'dist' 07:04:06 INFO - no previously-included directories found matching '.git*' 07:04:06 INFO - Successfully installed jsonschema functools32 07:04:06 INFO - Cleaning up... 07:04:06 INFO - Return code: 0 07:04:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:04:06 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')]} 07:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:06 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')]} 07:04:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:06 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 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:04:06 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 07:04:06 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 07:04:06 INFO - Using env: {'DISPLAY': ':0', 07:04:06 INFO - 'HOME': '/home/cltbld', 07:04:06 INFO - 'LANG': 'en_US.UTF-8', 07:04:06 INFO - 'LANGUAGE': 'en_US:en', 07:04:06 INFO - 'LOGNAME': 'cltbld', 07:04:06 INFO - 'MAIL': '/var/mail/cltbld', 07:04:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:06 INFO - 'MOZ_NO_REMOTE': '1', 07:04:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:06 INFO - 'NO_EM_RESTART': '1', 07:04:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:06 INFO - 'PWD': '/builds/slave/test', 07:04:06 INFO - 'SHELL': '/bin/bash', 07:04:06 INFO - 'SHLVL': '1', 07:04:06 INFO - 'TERM': 'linux', 07:04:06 INFO - 'TMOUT': '86400', 07:04:06 INFO - 'USER': 'cltbld', 07:04:06 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:06 INFO - '_': '/tools/buildbot/bin/python'} 07:04:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:04:06 INFO - Cleaning up... 07:04:06 INFO - Return code: 0 07:04:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:04:06 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')]} 07:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:06 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')]} 07:04:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:06 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 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:04:06 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 07:04:06 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 07:04:06 INFO - Using env: {'DISPLAY': ':0', 07:04:06 INFO - 'HOME': '/home/cltbld', 07:04:06 INFO - 'LANG': 'en_US.UTF-8', 07:04:06 INFO - 'LANGUAGE': 'en_US:en', 07:04:06 INFO - 'LOGNAME': 'cltbld', 07:04:06 INFO - 'MAIL': '/var/mail/cltbld', 07:04:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:06 INFO - 'MOZ_NO_REMOTE': '1', 07:04:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:06 INFO - 'NO_EM_RESTART': '1', 07:04:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:06 INFO - 'PWD': '/builds/slave/test', 07:04:06 INFO - 'SHELL': '/bin/bash', 07:04:06 INFO - 'SHLVL': '1', 07:04:06 INFO - 'TERM': 'linux', 07:04:06 INFO - 'TMOUT': '86400', 07:04:06 INFO - 'USER': 'cltbld', 07:04:06 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:06 INFO - '_': '/tools/buildbot/bin/python'} 07:04:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:06 INFO - Downloading/unpacking blobuploader==1.2.4 07:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:08 INFO - Downloading blobuploader-1.2.4.tar.gz 07:04:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:04:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:04: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 07:04: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 07:04: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 07:04: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 07:04:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:04:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:04: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 07:04: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 07:04: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 07:04: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 07:04:08 INFO - Downloading docopt-0.6.1.tar.gz 07:04:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:04:08 INFO - Installing collected packages: blobuploader, requests, docopt 07:04:08 INFO - Running setup.py install for blobuploader 07:04:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:04:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:04:08 INFO - Running setup.py install for requests 07:04:09 INFO - Running setup.py install for docopt 07:04:09 INFO - Successfully installed blobuploader requests docopt 07:04:09 INFO - Cleaning up... 07:04:09 INFO - Return code: 0 07:04:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:04: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')]} 07:04:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04: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')]} 07:04:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04: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 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:04: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 07:04: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 07:04:09 INFO - Using env: {'DISPLAY': ':0', 07:04:09 INFO - 'HOME': '/home/cltbld', 07:04:09 INFO - 'LANG': 'en_US.UTF-8', 07:04:09 INFO - 'LANGUAGE': 'en_US:en', 07:04:09 INFO - 'LOGNAME': 'cltbld', 07:04:09 INFO - 'MAIL': '/var/mail/cltbld', 07:04:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:09 INFO - 'MOZ_NO_REMOTE': '1', 07:04:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:09 INFO - 'NO_EM_RESTART': '1', 07:04:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:09 INFO - 'PWD': '/builds/slave/test', 07:04:09 INFO - 'SHELL': '/bin/bash', 07:04:09 INFO - 'SHLVL': '1', 07:04:09 INFO - 'TERM': 'linux', 07:04:09 INFO - 'TMOUT': '86400', 07:04:09 INFO - 'USER': 'cltbld', 07:04:09 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:09 INFO - '_': '/tools/buildbot/bin/python'} 07:04:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:04:09 INFO - Running setup.py (path:/tmp/pip-WouSND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:04:09 INFO - Running setup.py (path:/tmp/pip-JxFLak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:04:09 INFO - Running setup.py (path:/tmp/pip-z1FstN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:04:09 INFO - Running setup.py (path:/tmp/pip-VrCQXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:04:09 INFO - Running setup.py (path:/tmp/pip-mQIlWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:04:09 INFO - Running setup.py (path:/tmp/pip-fRIuox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:04:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:04:09 INFO - Running setup.py (path:/tmp/pip-D6bXFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:04:10 INFO - Running setup.py (path:/tmp/pip-yjdU9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:04:10 INFO - Running setup.py (path:/tmp/pip-MG24Wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:04:10 INFO - Running setup.py (path:/tmp/pip-9Etsdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:04:10 INFO - Running setup.py (path:/tmp/pip-ToB96q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:04:10 INFO - Running setup.py (path:/tmp/pip-L6tLiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:04:10 INFO - Running setup.py (path:/tmp/pip-v8vhsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:04:10 INFO - Running setup.py (path:/tmp/pip-eXMHAW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:10 INFO - Running setup.py (path:/tmp/pip-CZddwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:04:10 INFO - Running setup.py (path:/tmp/pip-SWtwHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:04:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:04:10 INFO - Running setup.py (path:/tmp/pip-CL9Kab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:04:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:04:10 INFO - Running setup.py install for manifestparser 07:04:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:04:10 INFO - Running setup.py install for mozcrash 07:04:10 INFO - Running setup.py install for mozdebug 07:04:11 INFO - Running setup.py install for mozdevice 07:04:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:04:11 INFO - Running setup.py install for mozfile 07:04:11 INFO - Running setup.py install for mozhttpd 07:04:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:04:11 INFO - Running setup.py install for mozinfo 07:04:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:04:11 INFO - Running setup.py install for mozInstall 07:04:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:04:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:04:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:04:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:04:11 INFO - Running setup.py install for mozleak 07:04:11 INFO - Running setup.py install for mozlog 07:04:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:04:11 INFO - Running setup.py install for moznetwork 07:04:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:04:11 INFO - Running setup.py install for mozprocess 07:04:11 INFO - Running setup.py install for mozprofile 07:04:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:04:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:04:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:04:12 INFO - Running setup.py install for mozrunner 07:04:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:04:12 INFO - Running setup.py install for mozscreenshot 07:04:12 INFO - Running setup.py install for moztest 07:04:12 INFO - Running setup.py install for mozversion 07:04:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:04:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:04:12 INFO - Cleaning up... 07:04:12 INFO - Return code: 0 07:04:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:04:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:12 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 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:04:12 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 07:04:12 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 07:04:12 INFO - Using env: {'DISPLAY': ':0', 07:04:12 INFO - 'HOME': '/home/cltbld', 07:04:12 INFO - 'LANG': 'en_US.UTF-8', 07:04:12 INFO - 'LANGUAGE': 'en_US:en', 07:04:12 INFO - 'LOGNAME': 'cltbld', 07:04:12 INFO - 'MAIL': '/var/mail/cltbld', 07:04:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:12 INFO - 'MOZ_NO_REMOTE': '1', 07:04:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:12 INFO - 'NO_EM_RESTART': '1', 07:04:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:12 INFO - 'PWD': '/builds/slave/test', 07:04:12 INFO - 'SHELL': '/bin/bash', 07:04:12 INFO - 'SHLVL': '1', 07:04:12 INFO - 'TERM': 'linux', 07:04:12 INFO - 'TMOUT': '86400', 07:04:12 INFO - 'USER': 'cltbld', 07:04:12 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:12 INFO - '_': '/tools/buildbot/bin/python'} 07:04:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:04:12 INFO - Running setup.py (path:/tmp/pip-9ErE61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:04:12 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)) 07:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:04:12 INFO - Running setup.py (path:/tmp/pip-lZZrAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:04:12 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)) 07:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:04:12 INFO - Running setup.py (path:/tmp/pip-w7xwNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:04:12 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)) 07:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:04:12 INFO - Running setup.py (path:/tmp/pip-ULW13B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:04:12 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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:04:13 INFO - Running setup.py (path:/tmp/pip-FZkJvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:04: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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:04:13 INFO - Running setup.py (path:/tmp/pip-dmZnko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:04: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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:04:13 INFO - Running setup.py (path:/tmp/pip-g8z2ZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:04: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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:04:13 INFO - Running setup.py (path:/tmp/pip-v9JHV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:04: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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:04:13 INFO - Running setup.py (path:/tmp/pip-J3QnGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:04:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:04:13 INFO - Running setup.py (path:/tmp/pip-68w9aI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:04:13 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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:04:13 INFO - Running setup.py (path:/tmp/pip-xiCYpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:04:13 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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:04:13 INFO - Running setup.py (path:/tmp/pip-r7KfJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:04:13 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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:04:13 INFO - Running setup.py (path:/tmp/pip-S97aAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:04:13 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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:04:13 INFO - Running setup.py (path:/tmp/pip-Ya_7Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:04:13 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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:13 INFO - Running setup.py (path:/tmp/pip-_y2ZFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:04:13 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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:04:13 INFO - Running setup.py (path:/tmp/pip-ybyCfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:04:13 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)) 07:04:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:04:13 INFO - Running setup.py (path:/tmp/pip-iPuOGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:04:13 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)) 07:04:13 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)) 07:04:13 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)) 07:04:13 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)) 07:04:13 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)) 07:04:13 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)) 07:04:13 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)) 07:04:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:04:13 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 07:04:13 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 07:04:13 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 07:04:13 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 07:04:15 INFO - Downloading blessings-1.6.tar.gz 07:04:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:04:15 INFO - Installing collected packages: blessings 07:04:15 INFO - Running setup.py install for blessings 07:04:15 INFO - Successfully installed blessings 07:04:15 INFO - Cleaning up... 07:04:15 INFO - Return code: 0 07:04:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:04:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:04:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:04:15 INFO - Reading from file tmpfile_stdout 07:04:15 INFO - Current package versions: 07:04:15 INFO - argparse == 1.2.1 07:04:15 INFO - blessings == 1.6 07:04:15 INFO - blobuploader == 1.2.4 07:04:15 INFO - docopt == 0.6.1 07:04:15 INFO - functools32 == 3.2.3-2 07:04:15 INFO - jsonschema == 2.5.1 07:04:15 INFO - manifestparser == 1.1 07:04:15 INFO - mozInstall == 1.13 07:04:15 INFO - mozcrash == 1.0 07:04:15 INFO - mozdebug == 0.1 07:04:15 INFO - mozdevice == 0.51 07:04:15 INFO - mozfile == 1.2 07:04:15 INFO - mozhttpd == 0.7 07:04:15 INFO - mozinfo == 0.10 07:04:15 INFO - mozleak == 0.1 07:04:15 INFO - mozlog == 3.5 07:04:15 INFO - moznetwork == 0.27 07:04:15 INFO - mozprocess == 0.25 07:04:15 INFO - mozprofile == 0.28 07:04:15 INFO - mozrunner == 6.13 07:04:15 INFO - mozscreenshot == 0.1 07:04:15 INFO - mozsystemmonitor == 0.3 07:04:15 INFO - moztest == 0.8 07:04:15 INFO - mozversion == 1.4 07:04:15 INFO - psutil == 3.1.1 07:04:15 INFO - requests == 1.2.3 07:04:15 INFO - wsgiref == 0.1.2 07:04:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:04:15 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')]} 07:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:15 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')]} 07:04:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:15 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 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:04:15 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 07:04:15 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 07:04:15 INFO - Using env: {'DISPLAY': ':0', 07:04:15 INFO - 'HOME': '/home/cltbld', 07:04:15 INFO - 'LANG': 'en_US.UTF-8', 07:04:15 INFO - 'LANGUAGE': 'en_US:en', 07:04:15 INFO - 'LOGNAME': 'cltbld', 07:04:15 INFO - 'MAIL': '/var/mail/cltbld', 07:04:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:15 INFO - 'MOZ_NO_REMOTE': '1', 07:04:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:15 INFO - 'NO_EM_RESTART': '1', 07:04:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:15 INFO - 'PWD': '/builds/slave/test', 07:04:15 INFO - 'SHELL': '/bin/bash', 07:04:15 INFO - 'SHLVL': '1', 07:04:15 INFO - 'TERM': 'linux', 07:04:15 INFO - 'TMOUT': '86400', 07:04:15 INFO - 'USER': 'cltbld', 07:04:15 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:15 INFO - '_': '/tools/buildbot/bin/python'} 07:04:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:15 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)) 07:04:15 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)) 07:04:15 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)) 07:04:15 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)) 07:04:15 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)) 07:04:15 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)) 07:04:15 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)) 07:04:15 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)) 07:04:15 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)) 07:04:15 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:04:15 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 07:04:15 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 07:04:15 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 07:04:15 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 07:04:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 07:04:16 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)) 07:04:16 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)) 07:04:16 INFO - Installing collected packages: simplejson 07:04:16 INFO - Running setup.py install for simplejson 07:04:17 INFO - building 'simplejson._speedups' extension 07:04: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 07:04:17 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 07:04:17 INFO - Successfully installed simplejson 07:04:17 INFO - Cleaning up... 07:04:17 INFO - Return code: 0 07:04:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:04:17 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')]} 07:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:17 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')]} 07:04:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:17 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 0xd621f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cea60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, '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': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', '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 07:04:17 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 07:04:17 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 07:04:17 INFO - Using env: {'DISPLAY': ':0', 07:04:17 INFO - 'HOME': '/home/cltbld', 07:04:17 INFO - 'LANG': 'en_US.UTF-8', 07:04:17 INFO - 'LANGUAGE': 'en_US:en', 07:04:17 INFO - 'LOGNAME': 'cltbld', 07:04:17 INFO - 'MAIL': '/var/mail/cltbld', 07:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:17 INFO - 'MOZ_NO_REMOTE': '1', 07:04:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:17 INFO - 'NO_EM_RESTART': '1', 07:04:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:17 INFO - 'PWD': '/builds/slave/test', 07:04:17 INFO - 'SHELL': '/bin/bash', 07:04:17 INFO - 'SHLVL': '1', 07:04:17 INFO - 'TERM': 'linux', 07:04:17 INFO - 'TMOUT': '86400', 07:04:17 INFO - 'USER': 'cltbld', 07:04:17 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:17 INFO - '_': '/tools/buildbot/bin/python'} 07:04:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:04:18 INFO - Cleaning up... 07:04:18 INFO - Return code: 0 07:04:18 INFO - Running post-action listener: _resource_record_post_action 07:04:18 INFO - Running post-action listener: _start_resource_monitoring 07:04:18 INFO - Starting resource monitoring. 07:04:18 INFO - [mozharness: 2017-09-21 14:04:18.215721Z] Finished create-virtualenv step (success) 07:04:18 INFO - [mozharness: 2017-09-21 14:04:18.216249Z] Running install step. 07:04:18 INFO - Running pre-action listener: _resource_record_pre_action 07:04:18 INFO - Running main action method: install 07:04:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:04:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:04:18 INFO - Reading from file tmpfile_stdout 07:04:18 INFO - Detecting whether we're running mozinstall >=1.0... 07:04:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:04:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:04:18 INFO - Reading from file tmpfile_stdout 07:04:18 INFO - Output received: 07:04:18 INFO - Usage: mozinstall [options] installer 07:04:18 INFO - Options: 07:04:18 INFO - -h, --help show this help message and exit 07:04:18 INFO - -d DEST, --destination=DEST 07:04:18 INFO - Directory to install application into. [default: 07:04:18 INFO - "/builds/slave/test"] 07:04:18 INFO - --app=APP Application being installed. [default: firefox] 07:04:18 INFO - mkdir: /builds/slave/test/build/application 07:04:18 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'] 07:04:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:04:36 INFO - Reading from file tmpfile_stdout 07:04:36 INFO - Output received: 07:04:36 INFO - /builds/slave/test/build/application/firefox/firefox 07:04:36 INFO - Running post-action listener: _resource_record_post_action 07:04:36 INFO - [mozharness: 2017-09-21 14:04:36.430101Z] Finished install step (success) 07:04:36 INFO - [mozharness: 2017-09-21 14:04:36.430349Z] Running setup-mitmproxy step. 07:04:36 INFO - Running pre-action listener: _resource_record_pre_action 07:04:36 INFO - Running main action method: setup_mitmproxy 07:04:36 INFO - Skipping: mitmproxy is not required 07:04:36 INFO - Running post-action listener: _resource_record_post_action 07:04:36 INFO - [mozharness: 2017-09-21 14:04:36.431461Z] Finished setup-mitmproxy step (success) 07:04:36 INFO - [mozharness: 2017-09-21 14:04:36.431680Z] Running run-tests step. 07:04:36 INFO - Running pre-action listener: _resource_record_pre_action 07:04:36 INFO - Running pre-action listener: _set_gcov_prefix 07:04:36 INFO - Running main action method: run_tests 07:04:36 WARNING - Try message not found. 07:04:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:04:36 INFO - Python 2.7.3 07:04:36 INFO - Return code: 0 07:04:36 INFO - grabbing minidump binary from tooltool 07:04: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')]} 07:04: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 0x10d2090>, '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 0x10d2d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ccf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:04: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 07:04: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 07:04: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 07:04:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:04:36 INFO - Return code: 0 07:04:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:04:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:04:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:04:36 INFO - ENV: RUST_BACKTRACE is now full 07:04:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:04:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:04:36 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/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 07:04:36 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/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-007 --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 07:04:36 INFO - Using env: {'DISPLAY': ':0', 07:04:36 INFO - 'HOME': '/home/cltbld', 07:04:36 INFO - 'LANG': 'en_US.UTF-8', 07:04:36 INFO - 'LANGUAGE': 'en_US:en', 07:04:36 INFO - 'LOGNAME': 'cltbld', 07:04:36 INFO - 'MAIL': '/var/mail/cltbld', 07:04:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:04:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:04:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:36 INFO - 'MOZ_NO_REMOTE': '1', 07:04:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:04:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:36 INFO - 'NO_EM_RESTART': '1', 07:04:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:36 INFO - 'PWD': '/builds/slave/test', 07:04:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:04:36 INFO - 'RUST_BACKTRACE': 'full', 07:04:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:04:36 INFO - 'SHELL': '/bin/bash', 07:04:36 INFO - 'SHLVL': '1', 07:04:36 INFO - 'TERM': 'linux', 07:04:36 INFO - 'TMOUT': '86400', 07:04:36 INFO - 'USER': 'cltbld', 07:04:36 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:36 INFO - '_': '/tools/buildbot/bin/python'} 07:04:36 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/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-007', '--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 07:04:36 INFO - mozversion application_buildid: 20170921131427 07:04:36 INFO - mozversion application_changeset: 9caeafcec9982be90820e30bc699e851cb6af907 07:04:36 INFO - mozversion application_display_name: Nightly 07:04:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:04:36 INFO - mozversion application_name: Firefox 07:04:36 INFO - mozversion application_remotingname: firefox 07:04:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:04:36 INFO - mozversion application_vendor: Mozilla 07:04:36 INFO - mozversion application_version: 57.0a1 07:04:36 INFO - mozversion platform_buildid: 20170921131427 07:04:36 INFO - mozversion platform_changeset: 9caeafcec9982be90820e30bc699e851cb6af907 07:04:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:04:36 INFO - mozversion platform_version: 57.0a1 07:04:36 INFO - using testdate: 1506002676 07:04:36 INFO - actual date: 1506002676 07:04:36 INFO - starting webserver on 'localhost:43515' 07:04:36 INFO - SUITE-START | Running 2 tests 07:04:36 INFO - TEST-START | dromaeo_css 07:04:36 INFO - Initialising browser for dromaeo_css test... 07:04:36 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43515/getInfo.html -profile /tmp/tmpZU1P6s/profile 07:04:36 INFO - TEST-INFO | started process 11679 (/builds/slave/test/build/application/firefox/firefox http://localhost:43515/getInfo.html) 07:04:41 INFO - TEST-INFO | 11679: exit 0 07:04:41 INFO - Browser initialized. 07:04:41 INFO - Running cycle 1/1 for dromaeo_css test... 07:04:41 INFO - Using env: {'DISPLAY': ':0', 07:04:41 INFO - 'HOME': '/home/cltbld', 07:04:41 INFO - 'JSGC_DISABLE_POISONING': '1', 07:04:41 INFO - 'LANG': 'en_US.UTF-8', 07:04:41 INFO - 'LANGUAGE': 'en_US:en', 07:04:41 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:04:41 INFO - 'LOGNAME': 'cltbld', 07:04:41 INFO - 'MAIL': '/var/mail/cltbld', 07:04:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:04:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:04:41 INFO - 'MOZ_CRASHREPORTER': '1', 07:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:41 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:04:41 INFO - 'MOZ_NO_REMOTE': '1', 07:04:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:04:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:04:41 INFO - 'NO_EM_RESTART': '1', 07:04:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:04:41 INFO - 'PWD': '/builds/slave/test', 07:04:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:04:41 INFO - 'RUST_BACKTRACE': 'full', 07:04:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:04:41 INFO - 'SHELL': '/bin/bash', 07:04:41 INFO - 'SHLVL': '1', 07:04:41 INFO - 'TERM': 'linux', 07:04:41 INFO - 'TMOUT': '86400', 07:04:41 INFO - 'USER': 'cltbld', 07:04:41 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:04:41 INFO - '_': '/tools/buildbot/bin/python'} 07:04:41 INFO - TEST-INFO | started process 11913 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZU1P6s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:04:42 INFO - PID 11913 | 07:04:42 INFO - PID 11913 | (/builds/slave/test/build/application/firefox/firefox:11962): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:04:42 INFO - PID 11913 | 07:04:52 INFO - PID 11913 | RSS: Main: 156602368 07:04:52 INFO - PID 11913 | 07:07:50 INFO - PID 11913 | Cycle 1(1): loaded http://localhost:43515/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43515/tests/dromaeo/cssquery-ext.html) 07:07:51 INFO - PID 11913 | RSS: Main: 156008448 07:07:51 INFO - PID 11913 | 07:10:53 INFO - PID 11913 | Cycle 1(1): loaded http://localhost:43515/tests/dromaeo/cssquery-ext.html (next: http://localhost:43515/tests/dromaeo/cssquery-jquery.html) 07:10:53 INFO - PID 11913 | RSS: Main: 156811264 07:10:53 INFO - PID 11913 | 07:13:55 INFO - PID 11913 | Cycle 1(1): loaded http://localhost:43515/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43515/tests/dromaeo/cssquery-mootools.html) 07:13:56 INFO - PID 11913 | RSS: Main: 158150656 07:13:56 INFO - PID 11913 | 07:16:57 INFO - PID 11913 | Cycle 1(1): loaded http://localhost:43515/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43515/tests/dromaeo/cssquery-prototype.html) 07:16:58 INFO - PID 11913 | RSS: Main: 154628096 07:16:58 INFO - PID 11913 | 07:20:01 INFO - PID 11913 | Cycle 1(1): loaded http://localhost:43515/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43515/tests/dromaeo/cssquery-yui.html) 07:20:02 INFO - PID 11913 | RSS: Main: 158892032 07:20:02 INFO - PID 11913 | 07:23:04 INFO - PID 11913 | Cycle 1(1): loaded http://localhost:43515/tests/dromaeo/cssquery-yui.html (next: http://localhost:43515/tests/dromaeo/cssquery-dojo.html) 07:23:04 INFO - PID 11913 | RSS: Main: 160309248 07:23:04 INFO - PID 11913 | 07:23:04 INFO - PID 11913 | __start_tp_report 07:23:04 INFO - PID 11913 | _x_x_mozilla_page_load 07:23:04 INFO - PID 11913 | _x_x_mozilla_page_load_details 07:23:04 INFO - PID 11913 | |i|pagename|runs| 07:23:04 INFO - PID 11913 | |0;dojo.html;3122.8126312421273;3142.9214269643257;3144.575482309886;3153.1959350365655;3156.7632427567937;6133.080037994303;6231;6273.278572964111;6335.9683201583985;6386.457151142153;6232.532560058003;6275.309715931244;6275.933091374466;6327.525435592326;6363.71363288652;5774.393688662703;5841.7371218295275;5877.059670452729;5901.675407852566;5941.940580594182;5886.381929897363;5918.230630018092;5925.851853703649;5936.792212272571;5975.282966044081;5728.68492232927;5758.683272420015;5775.480206781389;5831.212786273863;5844.824655260328;5731.770729170828;5807.506361959238;5821.476067153976;5885.646861188299;5900.88198236035;5622.803201887955;5703.372629010805;5742.138679198112;5788.363280039192;5801.390853960315;6224.906626400608;6244.469220116295;6264.624122552661;6267.4986001119805;6289.528285378615;6716.059751634775;6780.254172041072;6792.320767923217;6802.965985170092;6815.454763618898;6756.2568117507035;6771.559848609824;6801.523893327469;6853.7943861684225;6888.827779305508;6130.846728831771;6200.62796232223;6209.2548894133;6225.8132256032395;6229.408206220402;6782.796516104525;6824.488163387766;6829.351211634887;6844.4182244508775;6876.518643694893;6555.344465553407;6624.768133115318;6718.227403848522;6719.428848547928;6731.865362692719;6727.798166055026;6733.696983635748;6798.184217893884;6803.047573339736;6814.625195614289;6507.154069970874;6639.535232533773;6665.833354166185;6705.932940670628;6796.72813087471;6727.596344219362;6796.728130874809;6799.218089919624;6799.524033317719;6802.897956530656;5305.36335639718;5330.813421530305;5342.572594192456;5345.86635334112;5355.22349259363;4573.222552166139;4597.885052873705;4621.145088158634;4621.91385024519;4635.281531362644;5799.18811366409;5813.88372232553;5834.416558344132;5853.09277062056;5874.559408044413;6816.4546836252985;6836.794896153124;6896.413804826546;6896.896546551806;6901.206361268519;5756.395578464201;5796.739146738407;5843.824685259449;5864.03243464832;5878.353381128072;5622.943770562324;5762.711864406847;5763.164341170507;5844.26946631671;5855.736491857877;6410.134631824737;6423.96788016057;6427.775027874002;6432.6462044588;6439.130717353098;6810.148731408475;6827.761028363983;6836.042953986545;6837.589744615339;6843.965780171067;6299.937000629935;6339.953907605193;6489.9026514603265;6521.771737989147;6637;6671.866562668816;6701.664916754045;6748.527603067738;6774.051632771318;6810.9318906810295;6571.5399922005;6777.288384719731;6822.1131252938785;6896.620685862125;6930.826729331807;6406.263279722892;6450;6453.032045193258;6466.224053113657;6467.644279564669;6483;6489.059086432535;6490.870182596416;6491.805245842632;6501.902471462838;6404.711787969646;6421.550491465552;6495.74017039332;6563.0811686364905;6594.538382313384;6581.967090164519;6645.468362531083;6714.832129196614;6761.661916904233;6767.627780471925;6425.132607097982;6426.003969384753;6427.485801135805;6513.934860651522;6518.250401203923 07:23:04 INFO - PID 11913 | |1;ext.html;839.8950131233596;845.9238668519832;856.8928883889512;865.0743704236465;872.0320444306823;39556.604433955654;40542.59457405425;40678.796606017;40773.79613101934;40943.59056409435;36811.447828282595;36826.631733682654;36896;37245.255094898086;37352;7235.746748863778;7350.59571723555;7355.374793142562;7358.963205183966;7360.852782944338;7254;7339.633018349088;7342.045534080562;7343.192248852622;7349.375303099243;7307.744228951988;7316.195218525984;7334.633268336588;7342.339189472947;7343.926560734404;7217.350438460538;7261.7821465355855;7262.709491620329;7275.454340924452;7279.308465695751;6604.801855944329;6610.603363798187;6644.966775166094;6654.534182607219;6665.766698165541;33357.6664233357;33506.32987340239;33599;33644.83177584096;33649;3285.0801775502896;3294.5552350432627;3295.143262751678;3295.2915123248617;3299.191698033973;26594.6010809838;26822;26836;26843.463130737277;26946.595801063;4482.641388688897;4517.412736344287;4522.4346956630425;4525.185466616008;4527.094581083797;177231;178603;179125;179743;180017.9999999987;21013.474663133544;41313;42157.57842421598;47397;48199.27701084487;48841;49144.262836056776;49234.507654923706;49408.25887611689;49517.257241141415;16155.919220403823;16198.271077801528;16241.431549895697;16288;16305.673886522203;13248.933755331162;13281.203127812361;13321.06752527333;13482.719141681524;13487.797683034762;13533.120347177599;13555.457781688621;13558.86441135576;13657.317134143452;13799.51701690436;5308.336457942757;5373.38206106295;5405.13517837152;5420.620556561082;5454.509094181463;1000.9199264058858;1010.2675560218782;1023.1814548361281;1026.199564339816;1044.0290529807353;22590.887045564996;22972.4256893579;22985.770142298363;23120.190793322152;23434;1101.130107215307;1109.3177695717077;1116.9497372618252;1119.1047162270152;1121.770037142384;11973.880261197452;12038.217515861441;12228.633141005785;12279.631611051504;12315.75368492643;216130;216274;216468;216519;217151;51344;51381.743091285054;51578.74210628998;51621.48378516242;51690;1384.2663388404162;1422.559006707903;1423.843377228504;1432.5272660022167;1454.934527946245;22567;22878.08487660475;22901;23132.421689457897;23136;37235;37251.25497490089;37252.25495490129;37258.44112338263;37321.81339093287;197225;197960;198719;198754;199199;33941.49087763636;34260;34316.14209644779;34833;34860.12849678778;43533.564664352954;44486.55513444953;44699.77650111729;44862.55137448584;45002.32496512489;31898.20254493656;32288;32340;32359.352812944082;32613.510797338535;31702.365952681277;32051;32053.198670032503;32109.678903210668;32301.51547726739;35999.64000359963;36292.274154516235;36409.63590364168;36578.26843463169;36586;31314.686853131177;31503;31577.52633710542;31812.681873180973;32167.356652867278;23682.289531314087;23797.286081417584;23967.640485392385;24376.146834861403;24396.6340504889 07:23:04 INFO - PID 11913 | |2;jquery.html;574.8706541028272;575.5108158065644;576.216345769753;589.3840936221648;593.9673317967512;21903.890480547594;22376.664350034764;22481.887590562066;22593;22666.886665566668;25285;25448.236552903396;25521.61717574238;25597.232083037492;25648.743512564917;6479.643619600932;6627.1716035495565;6641.302663220341;6644.601323920556;6647.534672572898;6095.634261944276;6490.967545162268;6565.179352580927;6635.502337324696;6678.966605166967;6663.267040625527;6666.533342666015;6667.399934005938;6669.899951500707;6672.499562532806;6648.734050637993;6660.83347916306;6662.133922590032;6671.199456065279;6680.298568650294;6157.568970172089;6194.132821405007;6200.906986395207;6209.254889413254;6215;27677.169684909284;28643.85678071617;29039.41921161586;29818.85090574555;30029.24926876824;1992.4221975627052;2010.336588925651;2031.9695204571942;2034.52188735647;2034.6947957806299;19508.512287192792;19583.11875965585;20429.080691368923;20439;20489.2828750993;2317.6291793313158;2354.835161538693;2415.7800310843086;2422.3338581889943;2422.6608274841537;140634;154527.22736386358;158031;160166;160593.19703401526;20978;21108.366748997556;21135;21320.466988325417;21876.359273054586;41329;41342.37986430206;41367.58632413697;41659.7917010413;41829.581704183176;11059.612913547988;11162.441877906072;11266.830997535044;11300.54797808095;11349.432528373549;9175.587098580447;9234.307426943005;9290.535473226448;9311.208547273558;9320.95339523298;11535.653930382103;11619.128565357632;11653.18427710069;11707.239029463228;11952.581659642045;3626.655467730561;3808;4089.427480152781;4132.586741325843;4252.640414072848;936.6674830435097;949.9572519236651;956.3401253135314;958.5638534466801;962.2302158273353;19362.515937101685;19384.70922936157;19499.317523886595;19714.70427943582;19927.800721992884;993.6919554937992;1006.4213077480478;1008.5209525475487;1016.2022812092472;1017.6234793126442;10811.459427028618;10871.782564348827;10872.402017888933;10914.890851091388;11003.834942475869;444606;460795;463856;464509;474194;107454;109712.4514377423;111041;111567.44216278866;111620;1144.9771004579861;1145.9972524041464;1164.0512981919708;1168.807146820782;1177.8998785103363;17422.216000279765;17729.46811595654;17730.290788368322;17977;18008.459746207634;56493.71753141208;56669.43330566642;56795.14807277977;56973.43026569847;57185.42814571968;68137.31862681507;68167.3183268161;68366.65816670885;68809;69259.65370173319;19874.602507949472;19947.301844435955;20001.59996800085;20035.39893803188;20102.89948550248;21996.230131945304;22486.437839054153;22573.43566410853;22774.77225227793;22805.77194228103;17466.388676396266;17739.911300443415;17752.023638699997;17774.377896773552;17788;15617.76573351378;15713.764293535378;15910.68178636444;16199.352025918832;16303.836961630232;17816.732749008515;18208.271669133086;18220.271189152285;18258.634827303646;18426.539336516693;19661.91859447744;19703.802961970196;19745.703814442506;19774.40676779699;19835.107420166416;17238.827611724224;17325;17634.82365176332;17668.91165544164;17699.82300176982 07:23:04 INFO - PID 11913 | |3;mootools.html;1341.3715898494572;1378.9106605781426;1405.9718805623884;1410.8589141085886;1413.7455258053553;14899.627509312273;15442.536723898287;15571.53285401437;15573.37706491739;15628.921855390736;15359.001664891774;15442.459513917016;15484.767728484081;15515.224238788072;15563.92218038911;2645.828021178622;2768.9169324920285;2770.1135636596296;2772.1960631416864;2772.3900741836765;2714.8506832124326;2787.456445993032;2796.2310364649784;2799.986000069997;2801.523740964041;2717.728227177286;2807.326241701987;2812.2406950123454;2816.267770379706;2818.9295267618268;2658.4815960887636;2695.353115252335;2723.4416944526424;2723.8638068096716;2724.101046654599;2796.0912703371487;2816.915492535207;2821.111349924767;2824.4633519631207;2826.053272153831;14585.854141458662;14971.92514037423;15033;15051.172185529791;15053.096814191184;8018.759437216893;8350.457220280723;8375.329973602098;8378.958105209496;8388.580570971488;5341.4658534146665;5405.972970135124;5540.168974653794;5575.609707320489;5623.718814059281;1970.241457039036;1971.280482623832;1976.071246514136;2029.1984666056971;2037.5619241862923;26804.865975670193;27081.187564373296;27109.86445067782;27160.185194444002;27163.45673086547;6766.898496522556;6785.864282714417;6873.828154296182;6944.666560051102;6980.232174460805;17340.393086241922;17387.391441299493;17394.217260223068;17396.130193490273;17459.21433535494;7950.403719721044;8099.473534220257;8114.918850811534;8172.6322315495945;8176;7264.055672762507;7365.337119659256;7366.042414486083;7417.703291868264;7426.517276377018;5186.870328241824;5444.782208711607;5458.044842152607;5477.452254774491;5493.862653433696;1400.7478653842202;1431.4417377222892;1588.9205539722968;1602;1642.5647203491085;902.7066203483894;909.9272058235325;918.2149262380702;922.5940586142076;930.02812061396;8815.955920220358;8821.911780882238;8856.291496680378;8936.374453788172;8965.820683586291;971.3249291742312;974.1573538888952;976.2482888176206;990.0940639314946;995.069609914732;4922.433920099163;5247.921281180786;5302.840914772563;5365.409804921455;5375.838724838261;21715;21907.342779716633;22152.778472215396;22386.55226895453;22503.212387566356;20626.484337891674;21091.15635374568;21171.364859054254;21459.67810482844;21625.78374216269;11077.169212309109;11113.83329250062;11337.829932551018;11364.772704545863;11377.772444551063;8403.789905252417;8473.78815529592;8526.914730852612;8628.050914399537;8642.481451112953;18142.183601738216;18243.726344105115;18502.722459162855;18599.34902278414;18667;19070.904645476683;19117.23531058742;19136.90431547889;19148;19511.60976780428;11085.113190944867;11166.274192177483;11172.38551879622;11309;11311.038561722016;13543.864561354654;13664.31678416095;13666.794998074838;13673.589792306248;13701.314934253447;11215;11249.550017999189;11292.830607541058;11400.828987565028;11588.420578970849;10820.188485863435;10859.34843909368;11125.721856953642;11283.097352211968;11322.660320190407;9544.379615324982;9784.951075244579;9787.755306117404;9789.951050244988;9801.754956126153;6717.697703603252;6755.86488270222;6804.285550017177;6816.522843401112;6864.519483636098;6950.930490695028;6958.408535274559;7036.648167591497;7075.504644827811;7176.96411517939 07:23:04 INFO - PID 11913 | |4;prototype.html;503.37832776521014;514.3544851211727;536.9181100083332;544.6976927805067;546.4371697151935;6174.691265436722;6262.467690246329;6274.466670333016;6286.3166940629235;6367.49060075194;6164.845878853031;6167.599106058103;6194.4115309045565;6208.044465993209;6243.625382477055;5104.872378190538;5111.258867464216;5114.360704911886;5135.101357262482;5136.972605478901;4780.259059845712;4915.508449155073;5013.273075404064;5130.486951304877;5145.408278048035;4876.561109500144;4898.779554920019;4905.8773530661665;4911.901761964758;4927.556519913207;4837.153498137812;4854.587360074398;4864.100141473838;4898.485659005824;4929.7781599828095;4374.562543745632;4641.582257596832;4736.626378350309;4747.264174053027;4805.951940480585;6060.878782424327;6061.151438798572;6107.541934354941;6111.297200821918;6145.692715364214;5827.6398058785835;5829.125631155318;5916.6687495313645;5917.497012753921;5943.197668314765;6501;6544.312847151028;6552.006552006545;6601.405873471363;6613.239477460105;5017.49825017499;5209.192575150858;5236.423993360727;5239.292695486099;5245.423003469616;6766.3233676632435;6793.320667933217;6794.490413219029;6851.691673874738;6951.339622735832;5384.19237114436;5396.9730151348995;5402.135658294733;5405.189221616789;5467.726613669301;5437.34751829783;5509;5516.282883225235;5551.111822108443;5554.444755468476;5895.469407753237;5905;5913.9112913306335;5977.163197152402;5990.940090599125;6626.403623673796;6633;6677.666116694146;6699.162604674416;6725.428338591275;1858.386732378312;1877.249100359867;1910.235905637756;1944.5333120051087;1947.7857435682063;1391.5407915387898;1398.7202559488144;1403.9368228429746;1414.974143745776;1445.104035497977;5636.689982050944;5638.126090455986;5669.943300566942;5702.116171993348;5744.167095771173;6507.186601674791;6546.541742078104;6662.866742665216;6665.200175978914;6678.966605166943;5596;5655.632383895034;5669.4849193500295;5701.258836351248;5710.771569137188;3512.5433693619666;3557.3418917500344;3588.4976103345552;3588.5334906461994;3615.837287322077;6456.257530383972;6487;6502.577332473374;6523.869522609616;6541.574797638138;6877.759278425331;6914.308569143146;6919.827004324932;6938.167419909542;6965.930340696528;6420.74317027323;6486.318936511692;6486.383793539606;6515.315891831479;6530.934690653033;6608.140941677551;6622.8344291393105;6637.6681165940545;6644.534882558174;6692.594555143474;6583.584529326218;6638.50211234169;6641.468682505291;6705.2959439258175;6719.428848547928;6342.587731797418;6348.809535713936;6367.713452894538;6398.808035758934;6449.355064493513;5352.464753524617;5363.570914326922;5401.3248343957;5430.647007944471;5454.645393083146;6457;6586.1438013058;6614.305497922649;6620.867582648417;6760.1549806274215;6254.7482906154655;6389.265234497906;6399.520035997226;6439.7424103035355;6457.483401327976;6399.424051835171;6414.151509094506;6431.48548116159;6459.580127291711;6522.7717029905625;6309.432151106423;6341.207349081365;6406.263279722892;6415.358464153547;6429.22849258092;6438.645874476949;6477.352264773485;6498.0830654957845;6606.207255129223;6609.867802643824;6243.43809057187;6307.463865571294;6328.272248691459;6369.172007638977;6377.74489020434 07:23:04 INFO - PID 11913 | |5;yui.html;846.6655671009947;888.4491615198582;899.7435730816718;915.9038300978397;947.2800671489669;18621.813781862194;19306.227750889946;19316.90341548294;19370.90314548427;19372.418827435184;17686.38097666582;17800.910995445003;18767.906160469214;18973.14620842062;19061;966.2994329111408;978.2516374973151;980.3774603126994;981.4552923127661;983.5475681186663;971.6987733802544;972.0716715536681;975.0834215837126;980.62736160259;982.6609819612258;1019.1490105761698;1035.875694916611;1036.865207523021;1040.401768982834;1059.110347308261;1043;1045.937243765369;1047.0367262118793;1047.8480620310088;1049;746.9962650186768;750.5571712689485;752.4281546025005;752.868248056588;754.9584772837492;19733.40799776009;20616.278430254868;20702.068406921575;20800.791992080187;20876.37370878876;21636.459088522755;21709.45726356838;22393.328200153857;22419.663705044437;22426.88786556073;17550.561235969075;17784.3775467858;17805.46583602494;17819.821801781945;17828.108594570218;3570.3037907608027;3631.636836316374;3661.7253705972157;3662.157703728131;3664.2671465706967;141656;141750;142488.2875585626;144273.2786336072;145450;17165.914170429318;17173;17233.22450489731;17289.337060702775;17539;31476.21309467236;31666.841665791984;31682.366352672816;31718.2070448236;31765;8681.6527338907;8749.212570868525;8824.514651694219;8883.466991980502;8924.8661270081;4383.320585309282;4487.057717879216;4509.436320459942;4517.073999830092;4518.954810451919;5303.549198318109;5438.129899216106;5438.830651409966;5494.36814766307;5532.30846144232;982.651158838602;1000.9349392289478;1001.2290536287019;1006.0342071611188;1012.5089331674133;628.8742251549709;644.7227692092351;659.3768888400416;665.1652176518535;672.5460314287836;11642.35967021805;11847.526098955945;12064.336461494526;12895.806562901564;12970.546030888874;662.3012721578684;676.2797620534116;681.7443458702986;687.8177283020003;689.4381079420257;4937.35814344133;4998.725070121105;5036.823711170092;5206.167013277857;5221.738913054332;120176.39911800386;122599.38700306442;123037.38481307715;126014.3699281516;126717.36641316919;106583;109608;112093.43953280344;112708.43645781718;118907;98498.50750746201;99819.50992695472;101295.49352253192;101598;101687.98312016933;12583;13149.53976610814;13524.526641567498;13530.18818870871;13544.458221671022;43760.56239437565;44824.32763508485;45771.77114114408;46369.76815115903;47417.28874066823;56577;56740.43259567351;57570.13644795415;58889;60119;18923.43229703111;19004.334848280243;19100.235990560777;19196.424107276805;19222.327218547285;21404;21517.354479365644;21598.676019859402;21615.45961350979;21695.891520542296;16553.503394898173;16797.496075117768;16895.83104168992;16966.23651935641;16976.83023169802;18248.087595620433;18272.177752000927;18318.450446486626;18451.90774046121;18529.166187521863;17195.914020429816;17420.738688919424;17447.127643618023;17532.2110505025;17651;13975.93012034974;14185.645358866112;14244;14339.139651620937;14614.780778288547;13059.804102938653;13286.800697989345;13368;13388.330583470983;13570.796438053634 07:23:04 INFO - PID 11913 | __end_tp_report 07:23:04 INFO - PID 11913 | __start_cc_report 07:23:04 INFO - PID 11913 | _x_x_mozilla_cycle_collect,294 07:23:04 INFO - PID 11913 | __end_cc_report 07:23:04 INFO - PID 11913 | __startTimestamp1506003784356__endTimestamp 07:23:04 INFO - PID 11913 | 07:23:04 INFO - PID 11913 | ------- Summary: start ------- 07:23:04 INFO - PID 11913 | Number of tests: 6 07:23:04 INFO - PID 11913 | 07:23:04 INFO - PID 11913 | [#0] dojo.html Cycles:165 Average:6203.97 Median:6425.13 stddev:746.76 (11.6%) stddev-sans-first:708.85 07:23:04 INFO - PID 11913 | Values: 3122.8 3142.9 3144.6 3153.2 3156.8 6133.1 6231.0 6273.3 6336.0 6386.5 6232.5 6275.3 6275.9 6327.5 6363.7 5774.4 5841.7 5877.1 5901.7 5941.9 5886.4 5918.2 5925.9 5936.8 5975.3 5728.7 5758.7 5775.5 5831.2 5844.8 5731.8 5807.5 5821.5 5885.6 5900.9 5622.8 5703.4 5742.1 5788.4 5801.4 6224.9 6244.5 6264.6 6267.5 6289.5 6716.1 6780.3 6792.3 6803.0 6815.5 6756.3 6771.6 6801.5 6853.8 6888.8 6130.8 6200.6 6209.3 6225.8 6229.4 6782.8 6824.5 6829.4 6844.4 6876.5 6555.3 6624.8 6718.2 6719.4 6731.9 6727.8 6733.7 6798.2 6803.0 6814.6 6507.2 6639.5 6665.8 6705.9 6796.7 6727.6 6796.7 6799.2 6799.5 6802.9 5305.4 5330.8 5342.6 5345.9 5355.2 4573.2 4597.9 4621.1 4621.9 4635.3 5799.2 5813.9 5834.4 5853.1 5874.6 6816.5 6836.8 6896.4 6896.9 6901.2 5756.4 5796.7 5843.8 5864.0 5878.4 5622.9 5762.7 5763.2 5844.3 5855.7 6410.1 6424.0 6427.8 6432.6 6439.1 6810.1 6827.8 6836.0 6837.6 6844.0 6299.9 6340.0 6489.9 6521.8 6637.0 6671.9 6701.7 6748.5 6774.1 6810.9 6571.5 6777.3 6822.1 6896.6 6930.8 6406.3 6450.0 6453.0 6466.2 6467.6 6483.0 6489.1 6490.9 6491.8 6501.9 6404.7 6421.6 6495.7 6563.1 6594.5 6582.0 6645.5 6714.8 6761.7 6767.6 6425.1 6426.0 6427.5 6513.9 6518.3 07:23:04 INFO - PID 11913 | 07:23:04 INFO - PID 11913 | [#1] ext.html Cycles:180 Average:36110.21 Median:18328.43 stddev:51412.21 (280.5%) stddev-sans-first:51488.22 07:23:04 INFO - PID 11913 | Values: 839.9 845.9 856.9 865.1 872.0 39556.6 40542.6 40678.8 40773.8 40943.6 36811.4 36826.6 36896.0 37245.3 37352.0 7235.7 7350.6 7355.4 7359.0 7360.9 7254.0 7339.6 7342.0 7343.2 7349.4 7307.7 7316.2 7334.6 7342.3 7343.9 7217.4 7261.8 7262.7 7275.5 7279.3 6604.8 6610.6 6645.0 6654.5 6665.8 33357.7 33506.3 33599.0 33644.8 33649.0 3285.1 3294.6 3295.1 3295.3 3299.2 26594.6 26822.0 26836.0 26843.5 26946.6 4482.6 4517.4 4522.4 4525.2 4527.1 177231.0 178603.0 179125.0 179743.0 180018.0 21013.5 41313.0 42157.6 47397.0 48199.3 48841.0 49144.3 49234.5 49408.3 49517.3 16155.9 16198.3 16241.4 16288.0 16305.7 13248.9 13281.2 13321.1 13482.7 13487.8 13533.1 13555.5 13558.9 13657.3 13799.5 5308.3 5373.4 5405.1 5420.6 5454.5 1000.9 1010.3 1023.2 1026.2 1044.0 22590.9 22972.4 22985.8 23120.2 23434.0 1101.1 1109.3 1116.9 1119.1 1121.8 11973.9 12038.2 12228.6 12279.6 12315.8 216130.0 216274.0 216468.0 216519.0 217151.0 51344.0 51381.7 51578.7 51621.5 51690.0 1384.3 1422.6 1423.8 1432.5 1454.9 22567.0 22878.1 22901.0 23132.4 23136.0 37235.0 37251.3 37252.3 37258.4 37321.8 197225.0 197960.0 198719.0 198754.0 199199.0 33941.5 34260.0 34316.1 34833.0 34860.1 43533.6 44486.6 44699.8 44862.6 45002.3 31898.2 32288.0 32340.0 32359.4 32613.5 31702.4 32051.0 32053.2 32109.7 32301.5 35999.6 36292.3 36409.6 36578.3 36586.0 31314.7 31503.0 31577.5 31812.7 32167.4 23682.3 23797.3 23967.6 24376.1 24396.6 07:23:04 INFO - PID 11913 | 07:23:04 INFO - PID 11913 | [#2] jquery.html Cycles:180 Average:35239.75 Median:22186.45 stddev:78578.08 (354.2%) stddev-sans-first:78755.41 07:23:04 INFO - PID 11913 | Values: 574.9 575.5 576.2 589.4 594.0 21903.9 22376.7 22481.9 22593.0 22666.9 25285.0 25448.2 25521.6 25597.2 25648.7 6479.6 6627.2 6641.3 6644.6 6647.5 6095.6 6491.0 6565.2 6635.5 6679.0 6663.3 6666.5 6667.4 6669.9 6672.5 6648.7 6660.8 6662.1 6671.2 6680.3 6157.6 6194.1 6200.9 6209.3 6215.0 27677.2 28643.9 29039.4 29818.9 30029.2 1992.4 2010.3 2032.0 2034.5 2034.7 19508.5 19583.1 20429.1 20439.0 20489.3 2317.6 2354.8 2415.8 2422.3 2422.7 140634.0 154527.2 158031.0 160166.0 160593.2 20978.0 21108.4 21135.0 21320.5 21876.4 41329.0 41342.4 41367.6 41659.8 41829.6 11059.6 11162.4 11266.8 11300.5 11349.4 9175.6 9234.3 9290.5 9311.2 9321.0 11535.7 11619.1 11653.2 11707.2 11952.6 3626.7 3808.0 4089.4 4132.6 4252.6 936.7 950.0 956.3 958.6 962.2 19362.5 19384.7 19499.3 19714.7 19927.8 993.7 1006.4 1008.5 1016.2 1017.6 10811.5 10871.8 10872.4 10914.9 11003.8 444606.0 460795.0 463856.0 464509.0 474194.0 107454.0 109712.5 111041.0 111567.4 111620.0 1145.0 1146.0 1164.1 1168.8 1177.9 17422.2 17729.5 17730.3 17977.0 18008.5 56493.7 56669.4 56795.1 56973.4 57185.4 68137.3 68167.3 68366.7 68809.0 69259.7 19874.6 19947.3 20001.6 20035.4 20102.9 21996.2 22486.4 22573.4 22774.8 22805.8 17466.4 17739.9 17752.0 17774.4 17788.0 15617.8 15713.8 15910.7 16199.4 16303.8 17816.7 18208.3 18220.3 18258.6 18426.5 19661.9 19703.8 19745.7 19774.4 19835.1 17238.8 17325.0 17634.8 17668.9 17699.8 07:23:04 INFO - PID 11913 | 07:23:04 INFO - PID 11913 | [#3] mootools.html Cycles:180 Average:9395.53 Median:2723.65 stddev:6705.13 (246.2%) stddev-sans-first:6696.63 07:23:04 INFO - PID 11913 | Values: 1341.4 1378.9 1406.0 1410.9 1413.7 14899.6 15442.5 15571.5 15573.4 15628.9 15359.0 15442.5 15484.8 15515.2 15563.9 2645.8 2768.9 2770.1 2772.2 2772.4 2714.9 2787.5 2796.2 2800.0 2801.5 2717.7 2807.3 2812.2 2816.3 2818.9 2658.5 2695.4 2723.4 2723.9 2724.1 2796.1 2816.9 2821.1 2824.5 2826.1 14585.9 14971.9 15033.0 15051.2 15053.1 8018.8 8350.5 8375.3 8379.0 8388.6 5341.5 5406.0 5540.2 5575.6 5623.7 1970.2 1971.3 1976.1 2029.2 2037.6 26804.9 27081.2 27109.9 27160.2 27163.5 6766.9 6785.9 6873.8 6944.7 6980.2 17340.4 17387.4 17394.2 17396.1 17459.2 7950.4 8099.5 8114.9 8172.6 8176.0 7264.1 7365.3 7366.0 7417.7 7426.5 5186.9 5444.8 5458.0 5477.5 5493.9 1400.7 1431.4 1588.9 1602.0 1642.6 902.7 909.9 918.2 922.6 930.0 8816.0 8821.9 8856.3 8936.4 8965.8 971.3 974.2 976.2 990.1 995.1 4922.4 5247.9 5302.8 5365.4 5375.8 21715.0 21907.3 22152.8 22386.6 22503.2 20626.5 21091.2 21171.4 21459.7 21625.8 11077.2 11113.8 11337.8 11364.8 11377.8 8403.8 8473.8 8526.9 8628.1 8642.5 18142.2 18243.7 18502.7 18599.3 18667.0 19070.9 19117.2 19136.9 19148.0 19511.6 11085.1 11166.3 11172.4 11309.0 11311.0 13543.9 13664.3 13666.8 13673.6 13701.3 11215.0 11249.6 11292.8 11400.8 11588.4 10820.2 10859.3 11125.7 11283.1 11322.7 9544.4 9785.0 9787.8 9790.0 9801.8 6717.7 6755.9 6804.3 6816.5 6864.5 6950.9 6958.4 7036.6 7075.5 7177.0 07:23:04 INFO - PID 11913 | 07:23:04 INFO - PID 11913 | [#4] prototype.html Cycles:180 Average:5560.39 Median:6166.22 stddev:1496.64 (24.3%) stddev-sans-first:1451.91 07:23:04 INFO - PID 11913 | Values: 503.4 514.4 536.9 544.7 546.4 6174.7 6262.5 6274.5 6286.3 6367.5 6164.8 6167.6 6194.4 6208.0 6243.6 5104.9 5111.3 5114.4 5135.1 5137.0 4780.3 4915.5 5013.3 5130.5 5145.4 4876.6 4898.8 4905.9 4911.9 4927.6 4837.2 4854.6 4864.1 4898.5 4929.8 4374.6 4641.6 4736.6 4747.3 4806.0 6060.9 6061.2 6107.5 6111.3 6145.7 5827.6 5829.1 5916.7 5917.5 5943.2 6501.0 6544.3 6552.0 6601.4 6613.2 5017.5 5209.2 5236.4 5239.3 5245.4 6766.3 6793.3 6794.5 6851.7 6951.3 5384.2 5397.0 5402.1 5405.2 5467.7 5437.3 5509.0 5516.3 5551.1 5554.4 5895.5 5905.0 5913.9 5977.2 5990.9 6626.4 6633.0 6677.7 6699.2 6725.4 1858.4 1877.2 1910.2 1944.5 1947.8 1391.5 1398.7 1403.9 1415.0 1445.1 5636.7 5638.1 5669.9 5702.1 5744.2 6507.2 6546.5 6662.9 6665.2 6679.0 5596.0 5655.6 5669.5 5701.3 5710.8 3512.5 3557.3 3588.5 3588.5 3615.8 6456.3 6487.0 6502.6 6523.9 6541.6 6877.8 6914.3 6919.8 6938.2 6965.9 6420.7 6486.3 6486.4 6515.3 6530.9 6608.1 6622.8 6637.7 6644.5 6692.6 6583.6 6638.5 6641.5 6705.3 6719.4 6342.6 6348.8 6367.7 6398.8 6449.4 5352.5 5363.6 5401.3 5430.6 5454.6 6457.0 6586.1 6614.3 6620.9 6760.2 6254.7 6389.3 6399.5 6439.7 6457.5 6399.4 6414.2 6431.5 6459.6 6522.8 6309.4 6341.2 6406.3 6415.4 6429.2 6438.6 6477.4 6498.1 6606.2 6609.9 6243.4 6307.5 6328.3 6369.2 6377.7 07:23:04 INFO - PID 11913 | 07:23:04 INFO - PID 11913 | [#5] yui.html Cycles:180 Average:25332.29 Median:19371.66 stddev:36113.69 (186.4%) stddev-sans-first:36168.20 07:23:04 INFO - PID 11913 | Values: 846.7 888.4 899.7 915.9 947.3 18621.8 19306.2 19316.9 19370.9 19372.4 17686.4 17800.9 18767.9 18973.1 19061.0 966.3 978.3 980.4 981.5 983.5 971.7 972.1 975.1 980.6 982.7 1019.1 1035.9 1036.9 1040.4 1059.1 1043.0 1045.9 1047.0 1047.8 1049.0 747.0 750.6 752.4 752.9 755.0 19733.4 20616.3 20702.1 20800.8 20876.4 21636.5 21709.5 22393.3 22419.7 22426.9 17550.6 17784.4 17805.5 17819.8 17828.1 3570.3 3631.6 3661.7 3662.2 3664.3 141656.0 141750.0 142488.3 144273.3 145450.0 17165.9 17173.0 17233.2 17289.3 17539.0 31476.2 31666.8 31682.4 31718.2 31765.0 8681.7 8749.2 8824.5 8883.5 8924.9 4383.3 4487.1 4509.4 4517.1 4519.0 5303.5 5438.1 5438.8 5494.4 5532.3 982.7 1000.9 1001.2 1006.0 1012.5 628.9 644.7 659.4 665.2 672.5 11642.4 11847.5 12064.3 12895.8 12970.5 662.3 676.3 681.7 687.8 689.4 4937.4 4998.7 5036.8 5206.2 5221.7 120176.4 122599.4 123037.4 126014.4 126717.4 106583.0 109608.0 112093.4 112708.4 118907.0 98498.5 99819.5 101295.5 101598.0 101688.0 12583.0 13149.5 13524.5 13530.2 13544.5 43760.6 44824.3 45771.8 46369.8 47417.3 56577.0 56740.4 57570.1 58889.0 60119.0 18923.4 19004.3 19100.2 19196.4 19222.3 21404.0 21517.4 21598.7 21615.5 21695.9 16553.5 16797.5 16895.8 16966.2 16976.8 18248.1 18272.2 18318.5 18451.9 18529.2 17195.9 17420.7 17447.1 17532.2 17651.0 13975.9 14185.6 14244.0 14339.1 14614.8 13059.8 13286.8 13368.0 13388.3 13570.8 07:23:04 INFO - PID 11913 | -------- Summary: end -------- 07:23:04 INFO - PID 11913 | 07:23:04 INFO - TEST-INFO | 11913: exit 0 07:23:05 INFO - TEST-OK | dromaeo_css | took 1108213ms 07:23:05 INFO - TEST-START | kraken 07:23:05 INFO - Initialising browser for kraken test... 07:23:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43515/getInfo.html -profile /tmp/tmpLSuU2L/profile 07:23:05 INFO - TEST-INFO | started process 12289 (/builds/slave/test/build/application/firefox/firefox http://localhost:43515/getInfo.html) 07:23:09 INFO - TEST-INFO | 12289: exit 0 07:23:09 INFO - Browser initialized. 07:23:09 INFO - Running cycle 1/1 for kraken test... 07:23:09 INFO - Using env: {'DISPLAY': ':0', 07:23:09 INFO - 'HOME': '/home/cltbld', 07:23:09 INFO - 'JSGC_DISABLE_POISONING': '1', 07:23:09 INFO - 'LANG': 'en_US.UTF-8', 07:23:09 INFO - 'LANGUAGE': 'en_US:en', 07:23:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 07:23:09 INFO - 'LOGNAME': 'cltbld', 07:23:09 INFO - 'MAIL': '/var/mail/cltbld', 07:23:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:23:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:23:09 INFO - 'MOZ_CRASHREPORTER': '1', 07:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:23:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 07:23:09 INFO - 'MOZ_NO_REMOTE': '1', 07:23:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:23:09 INFO - 'NO_EM_RESTART': '1', 07:23:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:23:09 INFO - 'PWD': '/builds/slave/test', 07:23:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:23:09 INFO - 'RUST_BACKTRACE': 'full', 07:23:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:23:09 INFO - 'SHELL': '/bin/bash', 07:23:09 INFO - 'SHLVL': '1', 07:23:09 INFO - 'TERM': 'linux', 07:23:09 INFO - 'TMOUT': '86400', 07:23:09 INFO - 'USER': 'cltbld', 07:23:09 INFO - 'XDG_SESSION_COOKIE': '923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286', 07:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:23:09 INFO - '_': '/tools/buildbot/bin/python'} 07:23:09 INFO - TEST-INFO | started process 12519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLSuU2L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 07:23:10 INFO - PID 12519 | 07:23:10 INFO - PID 12519 | (/builds/slave/test/build/application/firefox/firefox:12568): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 07:23:10 INFO - PID 12519 | 07:23:21 INFO - PID 12519 | RSS: Main: 156262400 07:23:21 INFO - PID 12519 | 07:23:23 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43515/tests/kraken/driver.html?testName=audio-beat-detection) 07:23:24 INFO - PID 12519 | RSS: Main: 162562048 07:23:24 INFO - PID 12519 | 07:23:27 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43515/tests/kraken/driver.html?testName=audio-dft) 07:23:27 INFO - PID 12519 | RSS: Main: 163221504 07:23:27 INFO - PID 12519 | 07:23:30 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43515/tests/kraken/driver.html?testName=audio-fft) 07:23:31 INFO - PID 12519 | RSS: Main: 158633984 07:23:31 INFO - PID 12519 | 07:23:33 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43515/tests/kraken/driver.html?testName=audio-oscillator) 07:23:34 INFO - PID 12519 | RSS: Main: 159178752 07:23:34 INFO - PID 12519 | 07:23:36 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43515/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:23:36 INFO - PID 12519 | RSS: Main: 159293440 07:23:36 INFO - PID 12519 | 07:23:40 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43515/tests/kraken/driver.html?testName=imaging-darkroom) 07:23:40 INFO - PID 12519 | RSS: Main: 161509376 07:23:40 INFO - PID 12519 | 07:23:44 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43515/tests/kraken/driver.html?testName=imaging-desaturate) 07:23:44 INFO - PID 12519 | RSS: Main: 162267136 07:23:44 INFO - PID 12519 | 07:23:47 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43515/tests/kraken/driver.html?testName=json-parse-financial) 07:23:48 INFO - PID 12519 | RSS: Main: 162217984 07:23:48 INFO - PID 12519 | 07:23:50 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43515/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:23:50 INFO - PID 12519 | RSS: Main: 162312192 07:23:50 INFO - PID 12519 | 07:23:52 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43515/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:23:53 INFO - PID 12519 | RSS: Main: 162246656 07:23:53 INFO - PID 12519 | 07:23:55 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43515/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:23:55 INFO - PID 12519 | RSS: Main: 158982144 07:23:55 INFO - PID 12519 | 07:23:58 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43515/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:23:58 INFO - PID 12519 | RSS: Main: 159084544 07:23:58 INFO - PID 12519 | 07:24:01 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43515/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:24:02 INFO - PID 12519 | RSS: Main: 159444992 07:24:02 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | Cycle 1(1): loaded http://localhost:43515/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43515/tests/kraken/driver.html?testName=ai-astar) 07:24:04 INFO - PID 12519 | RSS: Main: 161046528 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | __start_tp_report 07:24:04 INFO - PID 12519 | _x_x_mozilla_page_load 07:24:04 INFO - PID 12519 | _x_x_mozilla_page_load_details 07:24:04 INFO - PID 12519 | |i|pagename|runs| 07:24:04 INFO - PID 12519 | |0;ai-astar;106;107;107;108;107;109;107;106;114;108 07:24:04 INFO - PID 12519 | |1;audio-beat-detection;118;120;117;119;121;122;119;117;139;116 07:24:04 INFO - PID 12519 | |2;audio-dft;163;162;160;161;162;162;163;168;164;164 07:24:04 INFO - PID 12519 | |3;audio-fft;74;74;73;73;73;73;73;74;73;74 07:24:04 INFO - PID 12519 | |4;audio-oscillator;80;79;79;79;78;143;80;82;89;82 07:24:04 INFO - PID 12519 | |5;imaging-gaussian-blur;113;111;114;117;114;114;114;114;112;113 07:24:04 INFO - PID 12519 | |6;imaging-darkroom;151;148;148;150;147;148;148;149;149;150 07:24:04 INFO - PID 12519 | |7;imaging-desaturate;95;94;94;94;95;94;94;94;96;94 07:24:04 INFO - PID 12519 | |8;json-parse-financial;56;54;54;54;59;55;53;53;55;54 07:24:04 INFO - PID 12519 | |9;json-stringify-tinderbox;38;38;38;40;38;39;42;39;39;38 07:24:04 INFO - PID 12519 | |10;stanford-crypto-aes;71;73;76;73;73;73;74;71;73;75 07:24:04 INFO - PID 12519 | |11;stanford-crypto-ccm;114;108;115;117;116;111;112;113;110;121 07:24:04 INFO - PID 12519 | |12;stanford-crypto-pbkdf2;169;179;167;166;168;162;169;168;165;167 07:24:04 INFO - PID 12519 | |13;stanford-crypto-sha256-iterative;64;64;66;65;67;65;65;63;65;64 07:24:04 INFO - PID 12519 | __end_tp_report 07:24:04 INFO - PID 12519 | __start_cc_report 07:24:04 INFO - PID 12519 | _x_x_mozilla_cycle_collect,721 07:24:04 INFO - PID 12519 | __end_cc_report 07:24:04 INFO - PID 12519 | __startTimestamp1506003844469__endTimestamp 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | ------- Summary: start ------- 07:24:04 INFO - PID 12519 | Number of tests: 14 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#0] ai-astar Cycles:10 Average:107.90 Median:107.50 stddev:2.33 (2.2%) stddev-sans-first:2.37 07:24:04 INFO - PID 12519 | Values: 106.0 107.0 107.0 108.0 107.0 109.0 107.0 106.0 114.0 108.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#1] audio-beat-detection Cycles:10 Average:120.80 Median:119.50 stddev:6.66 (5.6%) stddev-sans-first:6.99 07:24:04 INFO - PID 12519 | Values: 118.0 120.0 117.0 119.0 121.0 122.0 119.0 117.0 139.0 116.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#2] audio-dft Cycles:10 Average:162.90 Median:163.00 stddev:2.18 (1.3%) stddev-sans-first:2.32 07:24:04 INFO - PID 12519 | Values: 163.0 162.0 160.0 161.0 162.0 162.0 163.0 168.0 164.0 164.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#3] audio-fft Cycles:10 Average:73.40 Median:73.50 stddev:0.52 (0.7%) stddev-sans-first:0.50 07:24:04 INFO - PID 12519 | Values: 74.0 74.0 73.0 73.0 73.0 73.0 73.0 74.0 73.0 74.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:80.00 stddev:19.89 (24.9%) stddev-sans-first:20.93 07:24:04 INFO - PID 12519 | Values: 80.0 79.0 79.0 79.0 78.0 143.0 80.0 82.0 89.0 82.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:1.58 (1.4%) stddev-sans-first:1.66 07:24:04 INFO - PID 12519 | Values: 113.0 111.0 114.0 117.0 114.0 114.0 114.0 114.0 112.0 113.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#6] imaging-darkroom Cycles:10 Average:148.80 Median:149.00 stddev:1.23 (0.8%) stddev-sans-first:1.01 07:24:04 INFO - PID 12519 | Values: 151.0 148.0 148.0 150.0 147.0 148.0 148.0 149.0 149.0 150.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 07:24:04 INFO - PID 12519 | Values: 95.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 96.0 94.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:54.50 stddev:1.77 (3.2%) stddev-sans-first:1.81 07:24:04 INFO - PID 12519 | Values: 56.0 54.0 54.0 54.0 59.0 55.0 53.0 53.0 55.0 54.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#9] json-stringify-tinderbox Cycles:10 Average:38.90 Median:39.00 stddev:1.29 (3.3%) stddev-sans-first:1.32 07:24:04 INFO - PID 12519 | Values: 38.0 38.0 38.0 40.0 38.0 39.0 42.0 39.0 39.0 38.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#10] stanford-crypto-aes Cycles:10 Average:73.20 Median:73.00 stddev:1.55 (2.1%) stddev-sans-first:1.42 07:24:04 INFO - PID 12519 | Values: 71.0 73.0 76.0 73.0 73.0 73.0 74.0 71.0 73.0 75.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#11] stanford-crypto-ccm Cycles:10 Average:113.70 Median:114.50 stddev:3.77 (3.3%) stddev-sans-first:4.00 07:24:04 INFO - PID 12519 | Values: 114.0 108.0 115.0 117.0 116.0 111.0 112.0 113.0 110.0 121.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.00 stddev:4.40 (2.6%) stddev-sans-first:4.65 07:24:04 INFO - PID 12519 | Values: 169.0 179.0 167.0 166.0 168.0 162.0 169.0 168.0 165.0 167.0 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.14 (1.7%) stddev-sans-first:1.17 07:24:04 INFO - PID 12519 | Values: 64.0 64.0 66.0 65.0 67.0 65.0 65.0 63.0 65.0 64.0 07:24:04 INFO - PID 12519 | -------- Summary: end -------- 07:24:04 INFO - PID 12519 | 07:24:04 INFO - PID 12519 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 07:24:05 INFO - TEST-INFO | 12519: exit 0 07:24:05 INFO - TEST-OK | kraken | took 60093ms 07:24:05 INFO - SUITE-END | took 1168s 07:24:05 INFO - Completed test suite (00:19:28) 07:24:05 INFO - javascript benchmark 07:24:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8632.303388396507, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3122.8126312421273, 3142.9214269643257, 3144.575482309886, 3153.1959350365655, 3156.7632427567937, 6133.080037994303, 6231.0, 6273.278572964111, 6335.9683201583985, 6386.457151142153, 6232.532560058003, 6275.309715931244, 6275.933091374466, 6327.525435592326, 6363.71363288652, 5774.393688662703, 5841.7371218295275, 5877.059670452729, 5901.675407852566, 5941.940580594182, 5886.381929897363, 5918.230630018092, 5925.851853703649, 5936.792212272571, 5975.282966044081, 5728.68492232927, 5758.683272420015, 5775.480206781389, 5831.212786273863, 5844.824655260328, 5731.770729170828, 5807.506361959238, 5821.476067153976, 5885.646861188299, 5900.88198236035, 5622.803201887955, 5703.372629010805, 5742.138679198112, 5788.363280039192, 5801.390853960315, 6224.906626400608, 6244.469220116295, 6264.624122552661, 6267.4986001119805, 6289.528285378615, 6716.059751634775, 6780.254172041072, 6792.320767923217, 6802.965985170092, 6815.454763618898, 6756.2568117507035, 6771.559848609824, 6801.523893327469, 6853.7943861684225, 6888.827779305508, 6130.846728831771, 6200.62796232223, 6209.2548894133, 6225.8132256032395, 6229.408206220402, 6782.796516104525, 6824.488163387766, 6829.351211634887, 6844.4182244508775, 6876.518643694893, 6555.344465553407, 6624.768133115318, 6718.227403848522, 6719.428848547928, 6731.865362692719, 6727.798166055026, 6733.696983635748, 6798.184217893884, 6803.047573339736, 6814.625195614289, 6507.154069970874, 6639.535232533773, 6665.833354166185, 6705.932940670628, 6796.72813087471, 6727.596344219362, 6796.728130874809, 6799.218089919624, 6799.524033317719, 6802.897956530656, 5305.36335639718, 5330.813421530305, 5342.572594192456, 5345.86635334112, 5355.22349259363, 4573.222552166139, 4597.885052873705, 4621.145088158634, 4621.91385024519, 4635.281531362644, 5799.18811366409, 5813.88372232553, 5834.416558344132, 5853.09277062056, 5874.559408044413, 6816.4546836252985, 6836.794896153124, 6896.413804826546, 6896.896546551806, 6901.206361268519, 5756.395578464201, 5796.739146738407, 5843.824685259449, 5864.03243464832, 5878.353381128072, 5622.943770562324, 5762.711864406847, 5763.164341170507, 5844.26946631671, 5855.736491857877, 6410.134631824737, 6423.96788016057, 6427.775027874002, 6432.6462044588, 6439.130717353098, 6810.148731408475, 6827.761028363983, 6836.042953986545, 6837.589744615339, 6843.965780171067, 6299.937000629935, 6339.953907605193, 6489.9026514603265, 6521.771737989147, 6637.0, 6671.866562668816, 6701.664916754045, 6748.527603067738, 6774.051632771318, 6810.9318906810295, 6571.5399922005, 6777.288384719731, 6822.1131252938785, 6896.620685862125, 6930.826729331807, 6406.263279722892, 6450.0, 6453.032045193258, 6466.224053113657, 6467.644279564669, 6483.0, 6489.059086432535, 6490.870182596416, 6491.805245842632, 6501.902471462838, 6404.711787969646, 6421.550491465552, 6495.74017039332, 6563.0811686364905, 6594.538382313384, 6581.967090164519, 6645.468362531083, 6714.832129196614, 6761.661916904233, 6767.627780471925, 6425.132607097982, 6426.003969384753, 6427.485801135805, 6513.934860651522, 6518.250401203923], "value": 6146.592029628827, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.8950131233596, 845.9238668519832, 856.8928883889512, 865.0743704236465, 872.0320444306823, 39556.604433955654, 40542.59457405425, 40678.796606017, 40773.79613101934, 40943.59056409435, 36811.447828282595, 36826.631733682654, 36896.0, 37245.255094898086, 37352.0, 7235.746748863778, 7350.59571723555, 7355.374793142562, 7358.963205183966, 7360.852782944338, 7254.0, 7339.633018349088, 7342.045534080562, 7343.192248852622, 7349.375303099243, 7307.744228951988, 7316.195218525984, 7334.633268336588, 7342.339189472947, 7343.926560734404, 7217.350438460538, 7261.7821465355855, 7262.709491620329, 7275.454340924452, 7279.308465695751, 6604.801855944329, 6610.603363798187, 6644.966775166094, 6654.534182607219, 6665.766698165541, 33357.6664233357, 33506.32987340239, 33599.0, 33644.83177584096, 33649.0, 3285.0801775502896, 3294.5552350432627, 3295.143262751678, 3295.2915123248617, 3299.191698033973, 26594.6010809838, 26822.0, 26836.0, 26843.463130737277, 26946.595801063, 4482.641388688897, 4517.412736344287, 4522.4346956630425, 4525.185466616008, 4527.094581083797, 177231.0, 178603.0, 179125.0, 179743.0, 180017.9999999987, 21013.474663133544, 41313.0, 42157.57842421598, 47397.0, 48199.27701084487, 48841.0, 49144.262836056776, 49234.507654923706, 49408.25887611689, 49517.257241141415, 16155.919220403823, 16198.271077801528, 16241.431549895697, 16288.0, 16305.673886522203, 13248.933755331162, 13281.203127812361, 13321.06752527333, 13482.719141681524, 13487.797683034762, 13533.120347177599, 13555.457781688621, 13558.86441135576, 13657.317134143452, 13799.51701690436, 5308.336457942757, 5373.38206106295, 5405.13517837152, 5420.620556561082, 5454.509094181463, 1000.9199264058858, 1010.2675560218782, 1023.1814548361281, 1026.199564339816, 1044.0290529807353, 22590.887045564996, 22972.4256893579, 22985.770142298363, 23120.190793322152, 23434.0, 1101.130107215307, 1109.3177695717077, 1116.9497372618252, 1119.1047162270152, 1121.770037142384, 11973.880261197452, 12038.217515861441, 12228.633141005785, 12279.631611051504, 12315.75368492643, 216130.0, 216274.0, 216468.0, 216519.0, 217151.0, 51344.0, 51381.743091285054, 51578.74210628998, 51621.48378516242, 51690.0, 1384.2663388404162, 1422.559006707903, 1423.843377228504, 1432.5272660022167, 1454.934527946245, 22567.0, 22878.08487660475, 22901.0, 23132.421689457897, 23136.0, 37235.0, 37251.25497490089, 37252.25495490129, 37258.44112338263, 37321.81339093287, 197225.0, 197960.0, 198719.0, 198754.0, 199199.0, 33941.49087763636, 34260.0, 34316.14209644779, 34833.0, 34860.12849678778, 43533.564664352954, 44486.55513444953, 44699.77650111729, 44862.55137448584, 45002.32496512489, 31898.20254493656, 32288.0, 32340.0, 32359.352812944082, 32613.510797338535, 31702.365952681277, 32051.0, 32053.198670032503, 32109.678903210668, 32301.51547726739, 35999.64000359963, 36292.274154516235, 36409.63590364168, 36578.26843463169, 36586.0, 31314.686853131177, 31503.0, 31577.52633710542, 31812.681873180973, 32167.356652867278, 23682.289531314087, 23797.286081417584, 23967.640485392385, 24376.146834861403, 24396.6340504889], "value": 16511.163216824865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [574.8706541028272, 575.5108158065644, 576.216345769753, 589.3840936221648, 593.9673317967512, 21903.890480547594, 22376.664350034764, 22481.887590562066, 22593.0, 22666.886665566668, 25285.0, 25448.236552903396, 25521.61717574238, 25597.232083037492, 25648.743512564917, 6479.643619600932, 6627.1716035495565, 6641.302663220341, 6644.601323920556, 6647.534672572898, 6095.634261944276, 6490.967545162268, 6565.179352580927, 6635.502337324696, 6678.966605166967, 6663.267040625527, 6666.533342666015, 6667.399934005938, 6669.899951500707, 6672.499562532806, 6648.734050637993, 6660.83347916306, 6662.133922590032, 6671.199456065279, 6680.298568650294, 6157.568970172089, 6194.132821405007, 6200.906986395207, 6209.254889413254, 6215.0, 27677.169684909284, 28643.85678071617, 29039.41921161586, 29818.85090574555, 30029.24926876824, 1992.4221975627052, 2010.336588925651, 2031.9695204571942, 2034.52188735647, 2034.6947957806299, 19508.512287192792, 19583.11875965585, 20429.080691368923, 20439.0, 20489.2828750993, 2317.6291793313158, 2354.835161538693, 2415.7800310843086, 2422.3338581889943, 2422.6608274841537, 140634.0, 154527.22736386358, 158031.0, 160166.0, 160593.19703401526, 20978.0, 21108.366748997556, 21135.0, 21320.466988325417, 21876.359273054586, 41329.0, 41342.37986430206, 41367.58632413697, 41659.7917010413, 41829.581704183176, 11059.612913547988, 11162.441877906072, 11266.830997535044, 11300.54797808095, 11349.432528373549, 9175.587098580447, 9234.307426943005, 9290.535473226448, 9311.208547273558, 9320.95339523298, 11535.653930382103, 11619.128565357632, 11653.18427710069, 11707.239029463228, 11952.581659642045, 3626.655467730561, 3808.0, 4089.427480152781, 4132.586741325843, 4252.640414072848, 936.6674830435097, 949.9572519236651, 956.3401253135314, 958.5638534466801, 962.2302158273353, 19362.515937101685, 19384.70922936157, 19499.317523886595, 19714.70427943582, 19927.800721992884, 993.6919554937992, 1006.4213077480478, 1008.5209525475487, 1016.2022812092472, 1017.6234793126442, 10811.459427028618, 10871.782564348827, 10872.402017888933, 10914.890851091388, 11003.834942475869, 444606.0, 460795.0, 463856.0, 464509.0, 474194.0, 107454.0, 109712.4514377423, 111041.0, 111567.44216278866, 111620.0, 1144.9771004579861, 1145.9972524041464, 1164.0512981919708, 1168.807146820782, 1177.8998785103363, 17422.216000279765, 17729.46811595654, 17730.290788368322, 17977.0, 18008.459746207634, 56493.71753141208, 56669.43330566642, 56795.14807277977, 56973.43026569847, 57185.42814571968, 68137.31862681507, 68167.3183268161, 68366.65816670885, 68809.0, 69259.65370173319, 19874.602507949472, 19947.301844435955, 20001.59996800085, 20035.39893803188, 20102.89948550248, 21996.230131945304, 22486.437839054153, 22573.43566410853, 22774.77225227793, 22805.77194228103, 17466.388676396266, 17739.911300443415, 17752.023638699997, 17774.377896773552, 17788.0, 15617.76573351378, 15713.764293535378, 15910.68178636444, 16199.352025918832, 16303.836961630232, 17816.732749008515, 18208.271669133086, 18220.271189152285, 18258.634827303646, 18426.539336516693, 19661.91859447744, 19703.802961970196, 19745.703814442506, 19774.40676779699, 19835.107420166416, 17238.827611724224, 17325.0, 17634.82365176332, 17668.91165544164, 17699.82300176982], "value": 12664.30227433613, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.3715898494572, 1378.9106605781426, 1405.9718805623884, 1410.8589141085886, 1413.7455258053553, 14899.627509312273, 15442.536723898287, 15571.53285401437, 15573.37706491739, 15628.921855390736, 15359.001664891774, 15442.459513917016, 15484.767728484081, 15515.224238788072, 15563.92218038911, 2645.828021178622, 2768.9169324920285, 2770.1135636596296, 2772.1960631416864, 2772.3900741836765, 2714.8506832124326, 2787.456445993032, 2796.2310364649784, 2799.986000069997, 2801.523740964041, 2717.728227177286, 2807.326241701987, 2812.2406950123454, 2816.267770379706, 2818.9295267618268, 2658.4815960887636, 2695.353115252335, 2723.4416944526424, 2723.8638068096716, 2724.101046654599, 2796.0912703371487, 2816.915492535207, 2821.111349924767, 2824.4633519631207, 2826.053272153831, 14585.854141458662, 14971.92514037423, 15033.0, 15051.172185529791, 15053.096814191184, 8018.759437216893, 8350.457220280723, 8375.329973602098, 8378.958105209496, 8388.580570971488, 5341.4658534146665, 5405.972970135124, 5540.168974653794, 5575.609707320489, 5623.718814059281, 1970.241457039036, 1971.280482623832, 1976.071246514136, 2029.1984666056971, 2037.5619241862923, 26804.865975670193, 27081.187564373296, 27109.86445067782, 27160.185194444002, 27163.45673086547, 6766.898496522556, 6785.864282714417, 6873.828154296182, 6944.666560051102, 6980.232174460805, 17340.393086241922, 17387.391441299493, 17394.217260223068, 17396.130193490273, 17459.21433535494, 7950.403719721044, 8099.473534220257, 8114.918850811534, 8172.6322315495945, 8176.0, 7264.055672762507, 7365.337119659256, 7366.042414486083, 7417.703291868264, 7426.517276377018, 5186.870328241824, 5444.782208711607, 5458.044842152607, 5477.452254774491, 5493.862653433696, 1400.7478653842202, 1431.4417377222892, 1588.9205539722968, 1602.0, 1642.5647203491085, 902.7066203483894, 909.9272058235325, 918.2149262380702, 922.5940586142076, 930.02812061396, 8815.955920220358, 8821.911780882238, 8856.291496680378, 8936.374453788172, 8965.820683586291, 971.3249291742312, 974.1573538888952, 976.2482888176206, 990.0940639314946, 995.069609914732, 4922.433920099163, 5247.921281180786, 5302.840914772563, 5365.409804921455, 5375.838724838261, 21715.0, 21907.342779716633, 22152.778472215396, 22386.55226895453, 22503.212387566356, 20626.484337891674, 21091.15635374568, 21171.364859054254, 21459.67810482844, 21625.78374216269, 11077.169212309109, 11113.83329250062, 11337.829932551018, 11364.772704545863, 11377.772444551063, 8403.789905252417, 8473.78815529592, 8526.914730852612, 8628.050914399537, 8642.481451112953, 18142.183601738216, 18243.726344105115, 18502.722459162855, 18599.34902278414, 18667.0, 19070.904645476683, 19117.23531058742, 19136.90431547889, 19148.0, 19511.60976780428, 11085.113190944867, 11166.274192177483, 11172.38551879622, 11309.0, 11311.038561722016, 13543.864561354654, 13664.31678416095, 13666.794998074838, 13673.589792306248, 13701.314934253447, 11215.0, 11249.550017999189, 11292.830607541058, 11400.828987565028, 11588.420578970849, 10820.188485863435, 10859.34843909368, 11125.721856953642, 11283.097352211968, 11322.660320190407, 9544.379615324982, 9784.951075244579, 9787.755306117404, 9789.951050244988, 9801.754956126153, 6717.697703603252, 6755.86488270222, 6804.285550017177, 6816.522843401112, 6864.519483636098, 6950.930490695028, 6958.408535274559, 7036.648167591497, 7075.504644827811, 7176.96411517939], "value": 6747.414033559998, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.37832776521014, 514.3544851211727, 536.9181100083332, 544.6976927805067, 546.4371697151935, 6174.691265436722, 6262.467690246329, 6274.466670333016, 6286.3166940629235, 6367.49060075194, 6164.845878853031, 6167.599106058103, 6194.4115309045565, 6208.044465993209, 6243.625382477055, 5104.872378190538, 5111.258867464216, 5114.360704911886, 5135.101357262482, 5136.972605478901, 4780.259059845712, 4915.508449155073, 5013.273075404064, 5130.486951304877, 5145.408278048035, 4876.561109500144, 4898.779554920019, 4905.8773530661665, 4911.901761964758, 4927.556519913207, 4837.153498137812, 4854.587360074398, 4864.100141473838, 4898.485659005824, 4929.7781599828095, 4374.562543745632, 4641.582257596832, 4736.626378350309, 4747.264174053027, 4805.951940480585, 6060.878782424327, 6061.151438798572, 6107.541934354941, 6111.297200821918, 6145.692715364214, 5827.6398058785835, 5829.125631155318, 5916.6687495313645, 5917.497012753921, 5943.197668314765, 6501.0, 6544.312847151028, 6552.006552006545, 6601.405873471363, 6613.239477460105, 5017.49825017499, 5209.192575150858, 5236.423993360727, 5239.292695486099, 5245.423003469616, 6766.3233676632435, 6793.320667933217, 6794.490413219029, 6851.691673874738, 6951.339622735832, 5384.19237114436, 5396.9730151348995, 5402.135658294733, 5405.189221616789, 5467.726613669301, 5437.34751829783, 5509.0, 5516.282883225235, 5551.111822108443, 5554.444755468476, 5895.469407753237, 5905.0, 5913.9112913306335, 5977.163197152402, 5990.940090599125, 6626.403623673796, 6633.0, 6677.666116694146, 6699.162604674416, 6725.428338591275, 1858.386732378312, 1877.249100359867, 1910.235905637756, 1944.5333120051087, 1947.7857435682063, 1391.5407915387898, 1398.7202559488144, 1403.9368228429746, 1414.974143745776, 1445.104035497977, 5636.689982050944, 5638.126090455986, 5669.943300566942, 5702.116171993348, 5744.167095771173, 6507.186601674791, 6546.541742078104, 6662.866742665216, 6665.200175978914, 6678.966605166943, 5596.0, 5655.632383895034, 5669.4849193500295, 5701.258836351248, 5710.771569137188, 3512.5433693619666, 3557.3418917500344, 3588.4976103345552, 3588.5334906461994, 3615.837287322077, 6456.257530383972, 6487.0, 6502.577332473374, 6523.869522609616, 6541.574797638138, 6877.759278425331, 6914.308569143146, 6919.827004324932, 6938.167419909542, 6965.930340696528, 6420.74317027323, 6486.318936511692, 6486.383793539606, 6515.315891831479, 6530.934690653033, 6608.140941677551, 6622.8344291393105, 6637.6681165940545, 6644.534882558174, 6692.594555143474, 6583.584529326218, 6638.50211234169, 6641.468682505291, 6705.2959439258175, 6719.428848547928, 6342.587731797418, 6348.809535713936, 6367.713452894538, 6398.808035758934, 6449.355064493513, 5352.464753524617, 5363.570914326922, 5401.3248343957, 5430.647007944471, 5454.645393083146, 6457.0, 6586.1438013058, 6614.305497922649, 6620.867582648417, 6760.1549806274215, 6254.7482906154655, 6389.265234497906, 6399.520035997226, 6439.7424103035355, 6457.483401327976, 6399.424051835171, 6414.151509094506, 6431.48548116159, 6459.580127291711, 6522.7717029905625, 6309.432151106423, 6341.207349081365, 6406.263279722892, 6415.358464153547, 6429.22849258092, 6438.645874476949, 6477.352264773485, 6498.0830654957845, 6606.207255129223, 6609.867802643824, 6243.43809057187, 6307.463865571294, 6328.272248691459, 6369.172007638977, 6377.74489020434], "value": 5134.4863209277955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [846.6655671009947, 888.4491615198582, 899.7435730816718, 915.9038300978397, 947.2800671489669, 18621.813781862194, 19306.227750889946, 19316.90341548294, 19370.90314548427, 19372.418827435184, 17686.38097666582, 17800.910995445003, 18767.906160469214, 18973.14620842062, 19061.0, 966.2994329111408, 978.2516374973151, 980.3774603126994, 981.4552923127661, 983.5475681186663, 971.6987733802544, 972.0716715536681, 975.0834215837126, 980.62736160259, 982.6609819612258, 1019.1490105761698, 1035.875694916611, 1036.865207523021, 1040.401768982834, 1059.110347308261, 1043.0, 1045.937243765369, 1047.0367262118793, 1047.8480620310088, 1049.0, 746.9962650186768, 750.5571712689485, 752.4281546025005, 752.868248056588, 754.9584772837492, 19733.40799776009, 20616.278430254868, 20702.068406921575, 20800.791992080187, 20876.37370878876, 21636.459088522755, 21709.45726356838, 22393.328200153857, 22419.663705044437, 22426.88786556073, 17550.561235969075, 17784.3775467858, 17805.46583602494, 17819.821801781945, 17828.108594570218, 3570.3037907608027, 3631.636836316374, 3661.7253705972157, 3662.157703728131, 3664.2671465706967, 141656.0, 141750.0, 142488.2875585626, 144273.2786336072, 145450.0, 17165.914170429318, 17173.0, 17233.22450489731, 17289.337060702775, 17539.0, 31476.21309467236, 31666.841665791984, 31682.366352672816, 31718.2070448236, 31765.0, 8681.6527338907, 8749.212570868525, 8824.514651694219, 8883.466991980502, 8924.8661270081, 4383.320585309282, 4487.057717879216, 4509.436320459942, 4517.073999830092, 4518.954810451919, 5303.549198318109, 5438.129899216106, 5438.830651409966, 5494.36814766307, 5532.30846144232, 982.651158838602, 1000.9349392289478, 1001.2290536287019, 1006.0342071611188, 1012.5089331674133, 628.8742251549709, 644.7227692092351, 659.3768888400416, 665.1652176518535, 672.5460314287836, 11642.35967021805, 11847.526098955945, 12064.336461494526, 12895.806562901564, 12970.546030888874, 662.3012721578684, 676.2797620534116, 681.7443458702986, 687.8177283020003, 689.4381079420257, 4937.35814344133, 4998.725070121105, 5036.823711170092, 5206.167013277857, 5221.738913054332, 120176.39911800386, 122599.38700306442, 123037.38481307715, 126014.3699281516, 126717.36641316919, 106583.0, 109608.0, 112093.43953280344, 112708.43645781718, 118907.0, 98498.50750746201, 99819.50992695472, 101295.49352253192, 101598.0, 101687.98312016933, 12583.0, 13149.53976610814, 13524.526641567498, 13530.18818870871, 13544.458221671022, 43760.56239437565, 44824.32763508485, 45771.77114114408, 46369.76815115903, 47417.28874066823, 56577.0, 56740.43259567351, 57570.13644795415, 58889.0, 60119.0, 18923.43229703111, 19004.334848280243, 19100.235990560777, 19196.424107276805, 19222.327218547285, 21404.0, 21517.354479365644, 21598.676019859402, 21615.45961350979, 21695.891520542296, 16553.503394898173, 16797.496075117768, 16895.83104168992, 16966.23651935641, 16976.83023169802, 18248.087595620433, 18272.177752000927, 18318.450446486626, 18451.90774046121, 18529.166187521863, 17195.914020429816, 17420.738688919424, 17447.127643618023, 17532.2110505025, 17651.0, 13975.93012034974, 14185.645358866112, 14244.0, 14339.139651620937, 14614.780778288547, 13059.804102938653, 13286.800697989345, 13368.0, 13388.330583470983, 13570.796438053634], "value": 9291.961767485596, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1422.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 107.0, 108.0, 107.0, 109.0, 107.0, 106.0, 114.0, 108.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 120.0, 117.0, 119.0, 121.0, 122.0, 119.0, 117.0, 139.0, 116.0], "value": 120.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 160.0, 161.0, 162.0, 162.0, 163.0, 168.0, 164.0, 164.0], "value": 162.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 73.0, 73.0, 73.0, 73.0, 74.0, 73.0, 74.0], "value": 73.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 79.0, 78.0, 143.0, 80.0, 82.0, 89.0, 82.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 114.0, 117.0, 114.0, 114.0, 114.0, 114.0, 112.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 148.0, 150.0, 147.0, 148.0, 148.0, 149.0, 149.0, 150.0], "value": 148.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 96.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 54.0, 59.0, 55.0, 53.0, 53.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 40.0, 38.0, 39.0, 42.0, 39.0, 39.0, 38.0], "value": 38.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 76.0, 73.0, 73.0, 73.0, 74.0, 71.0, 73.0, 75.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 115.0, 117.0, 116.0, 111.0, 112.0, 113.0, 110.0, 121.0], "value": 113.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 179.0, 167.0, 166.0, 168.0, 162.0, 169.0, 168.0, 165.0, 167.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 66.0, 65.0, 67.0, 65.0, 65.0, 63.0, 65.0, 64.0], "value": 64.8, "unit": "score"}]}]} 07:24:05 INFO - Return code: 0 07:24:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:24:05 INFO - # TBPL SUCCESS # 07:24:05 INFO - Running post-action listener: _package_coverage_data 07:24:05 INFO - Running post-action listener: _resource_record_post_action 07:24:05 INFO - [mozharness: 2017-09-21 14:24:05.259025Z] Finished run-tests step (success) 07:24:05 INFO - Running post-run listener: _resource_record_post_run 07:24:05 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 343588864; Read time: 20; Write time: 215004 07:24:05 INFO - TinderboxPrint: CPU usage
13.1% 07:24:05 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 07:24:05 INFO - TinderboxPrint: I/O write bytes / time
343,588,864 / 215,004 07:24:05 INFO - TinderboxPrint: CPU idle
8,224.3 (86.9%) 07:24:05 INFO - TinderboxPrint: CPU user
1,190.7 (12.6%) 07:24:05 INFO - TinderboxPrint: Swap in / out
0 / 0 07:24:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10321920; Read time: 0; Write time: 1268 07:24:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 07:24:05 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 4096; Write bytes: 333266944; Read time: 4; Write time: 213736 07:24:05 INFO - Running post-run listener: _upload_blobber_files 07:24:05 INFO - Blob upload gear active. 07:24:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:24:05 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 07:24:05 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'] 07:24:05 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 07:24:06 INFO - (blobuploader) - INFO - Open directory for files ... 07:24:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:24:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:24:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:07 INFO - (blobuploader) - INFO - Done attempting. 07:24:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:24:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:24:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:14 INFO - (blobuploader) - INFO - Done attempting. 07:24:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:24:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:24:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:15 INFO - (blobuploader) - INFO - Done attempting. 07:24:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:24:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:24:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:16 INFO - (blobuploader) - INFO - Done attempting. 07:24:16 INFO - (blobuploader) - INFO - Iteration through files over. 07:24:16 INFO - Return code: 0 07:24:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:24:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:24:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5179dfc7b262b71e0bc04c3e5accf2d36377af88565d2ff886b782afee6cc4a9877e7d94f12822c124e8acb97f641d2e186f95f6615aa536ae7554618ee3f129", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8242363daf8610a4c1dee151358fe62a7ca61fc29e0b889360f5cbd244b793ea4508b44cb7cc1524687dd6497889e069014026bb5e4ce15ab9a88ae1179f1c14", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b16df8b0d13ceeb48e482cb66ba94bc4d97309794566765667ae40dead4c1222f698fe7bf6dec9c795bd2ba56e2beea6773cfd7c7d4c1a095b1b66ffe75a0f8b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0fc9c5814cae2fa79b5eb6e5f5f34c61df230df7e32fb62f155b6359f30739f063b03046807b7a0cc264752831df04e66076c1b7ec88b2a4e9fb023fe44b52be"} 07:24:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:24:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:24:16 INFO - Contents: 07:24:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5179dfc7b262b71e0bc04c3e5accf2d36377af88565d2ff886b782afee6cc4a9877e7d94f12822c124e8acb97f641d2e186f95f6615aa536ae7554618ee3f129", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8242363daf8610a4c1dee151358fe62a7ca61fc29e0b889360f5cbd244b793ea4508b44cb7cc1524687dd6497889e069014026bb5e4ce15ab9a88ae1179f1c14", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b16df8b0d13ceeb48e482cb66ba94bc4d97309794566765667ae40dead4c1222f698fe7bf6dec9c795bd2ba56e2beea6773cfd7c7d4c1a095b1b66ffe75a0f8b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0fc9c5814cae2fa79b5eb6e5f5f34c61df230df7e32fb62f155b6359f30739f063b03046807b7a0cc264752831df04e66076c1b7ec88b2a4e9fb023fe44b52be"} 07:24:16 INFO - Running post-run listener: copy_logs_to_upload_dir 07:24:16 INFO - Copying logs to upload dir... 07:24:16 INFO - mkdir: /builds/slave/test/build/upload/logs 07:24:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.581128 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2017-09-21 07:24:16.773704) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 07:24:16.776885) ========= 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=923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286 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/5179dfc7b262b71e0bc04c3e5accf2d36377af88565d2ff886b782afee6cc4a9877e7d94f12822c124e8acb97f641d2e186f95f6615aa536ae7554618ee3f129", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8242363daf8610a4c1dee151358fe62a7ca61fc29e0b889360f5cbd244b793ea4508b44cb7cc1524687dd6497889e069014026bb5e4ce15ab9a88ae1179f1c14", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b16df8b0d13ceeb48e482cb66ba94bc4d97309794566765667ae40dead4c1222f698fe7bf6dec9c795bd2ba56e2beea6773cfd7c7d4c1a095b1b66ffe75a0f8b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0fc9c5814cae2fa79b5eb6e5f5f34c61df230df7e32fb62f155b6359f30739f063b03046807b7a0cc264752831df04e66076c1b7ec88b2a4e9fb023fe44b52be"} build_url:https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011331 build_url: 'https://queue.taskcluster.net/v1/task/OR_WzdNeS6ee0KU9V4iMiw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5179dfc7b262b71e0bc04c3e5accf2d36377af88565d2ff886b782afee6cc4a9877e7d94f12822c124e8acb97f641d2e186f95f6615aa536ae7554618ee3f129", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8242363daf8610a4c1dee151358fe62a7ca61fc29e0b889360f5cbd244b793ea4508b44cb7cc1524687dd6497889e069014026bb5e4ce15ab9a88ae1179f1c14", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b16df8b0d13ceeb48e482cb66ba94bc4d97309794566765667ae40dead4c1222f698fe7bf6dec9c795bd2ba56e2beea6773cfd7c7d4c1a095b1b66ffe75a0f8b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0fc9c5814cae2fa79b5eb6e5f5f34c61df230df7e32fb62f155b6359f30739f063b03046807b7a0cc264752831df04e66076c1b7ec88b2a4e9fb023fe44b52be"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-21 07:24:16.807134) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:24:16.807505) ========= 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=923d835db2ba3686ebe126b700000192-1506002362.459262-1044247286 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004531 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-21 07:24:16.892472) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 07:24:16.892802) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-21 07:24:16.893115) ========= ========= Total master_lag: 0.21 =========