builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-006 starttime: 1504464789.13 results: success (0) revision: 0afabd3e5c27b0036517b96eecb1f8553d027179 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:09.127218) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:09.127757) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:09.128215) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:09.177923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:09.178302) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 11:53:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 68.3M=0s 2017-09-03 11:53:09 (68.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.224084 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:09.434814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:09.435125) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018263 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:09.486876) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-03 11:53:09.487223) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0afabd3e5c27b0036517b96eecb1f8553d027179 --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 0afabd3e5c27b0036517b96eecb1f8553d027179 --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=e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 11:53:09,545 truncating revision to first 12 chars 2017-09-03 11:53:09,545 Setting DEBUG logging. 2017-09-03 11:53:09,545 attempt 1/10 2017-09-03 11:53:09,545 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0afabd3e5c27?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-03 11:53:10,697 unpacking tar archive at: mozilla-central-0afabd3e5c27/testing/mozharness/ program finished with exit code 0 elapsedTime=1.547148 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-03 11:53:11.050254) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:11.050583) ========= script_repo_revision: 0afabd3e5c27b0036517b96eecb1f8553d027179 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:11.050966) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:11.051230) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 11:53:11.064903) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-03 11:53:11.065269) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:53:11 INFO - MultiFileLogger online at 20170903 11:53:11 in /builds/slave/test 11:53:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:53:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:53:11 INFO - {'append_to_log': False, 11:53:11 INFO - 'base_work_dir': '/builds/slave/test', 11:53:11 INFO - 'blob_upload_branch': 'Firefox', 11:53:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:53:11 INFO - 'branch': 'Firefox', 11:53:11 INFO - 'buildbot_json_path': 'buildprops.json', 11:53:11 INFO - 'code_coverage': False, 11:53:11 INFO - 'config_files': ('talos/linux_config.py',), 11:53:11 INFO - 'default_actions': ('clobber', 11:53:11 INFO - 'read-buildbot-config', 11:53:11 INFO - 'download-and-extract', 11:53:11 INFO - 'populate-webroot', 11:53:11 INFO - 'create-virtualenv', 11:53:11 INFO - 'install', 11:53:11 INFO - 'setup-mitmproxy', 11:53:11 INFO - 'run-tests'), 11:53:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:53:11 INFO - 'disable_ccov_upload': False, 11:53:11 INFO - 'disable_stylo': False, 11:53:11 INFO - 'download_minidump_stackwalk': True, 11:53:11 INFO - 'download_symbols': 'ondemand', 11:53:11 INFO - 'enable_stylo': False, 11:53:11 INFO - 'enable_webrender': False, 11:53:11 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:53:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:53:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:53:11 INFO - 'gecko_profile': False, 11:53:11 INFO - 'gecko_profile_interval': 0, 11:53:11 INFO - 'installer_path': 'installer.exe', 11:53:11 INFO - 'log_level': 'info', 11:53:11 INFO - 'log_name': 'talos', 11:53:11 INFO - 'log_to_console': True, 11:53:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:53:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:53:11 INFO - 'opt_config_files': (), 11:53:11 INFO - 'pip_index': False, 11:53:11 INFO - 'suite': 'dromaeojs-e10s', 11:53:11 INFO - 'system_bits': '32', 11:53:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:53:11 INFO - 'title': 'talos-linux64-ix-006', 11:53:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:53:11 INFO - 'use_talos_json': True, 11:53:11 INFO - 'verify': 'False', 11:53:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:53:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:53:11 INFO - 'work_dir': 'build'} 11:53:11 INFO - [mozharness: 2017-09-03 18:53:11.354242Z] Running clobber step. 11:53:11 INFO - Running pre-action listener: _resource_record_pre_action 11:53:11 INFO - Running main action method: clobber 11:53:11 INFO - rmtree: /builds/slave/test/build 11:53:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:53:12 INFO - Running post-action listener: _resource_record_post_action 11:53:12 INFO - [mozharness: 2017-09-03 18:53:12.335900Z] Finished clobber step (success) 11:53:12 INFO - [mozharness: 2017-09-03 18:53:12.335998Z] Running read-buildbot-config step. 11:53:12 INFO - Running pre-action listener: _resource_record_pre_action 11:53:12 INFO - Running main action method: read_buildbot_config 11:53:12 INFO - Using buildbot properties: 11:53:12 INFO - { 11:53:12 INFO - "project": "", 11:53:12 INFO - "product": "firefox", 11:53:12 INFO - "who": "ffxbld@noreply.mozilla.org", 11:53:12 INFO - "installer_path": "public/build/target.tar.bz2", 11:53:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:53:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:53:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 11:53:12 INFO - "stage_platform": "linux64-pgo", 11:53:12 INFO - "basedir": "/builds/slave/test", 11:53:12 INFO - "buildnumber": 155, 11:53:12 INFO - "platform": "ubuntu64_hw", 11:53:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:53:12 INFO - "slavebuilddir": "test", 11:53:12 INFO - "taskId": "AY3aMVQ3RDKQYNb7X1J1dQ", 11:53:12 INFO - "branch": "mozilla-central", 11:53:12 INFO - "script_repo_revision": "production", 11:53:12 INFO - "revision": "0afabd3e5c27b0036517b96eecb1f8553d027179", 11:53:12 INFO - "slavename": "talos-linux64-ix-006", 11:53:12 INFO - "repo_path": "mozilla-central" 11:53:12 INFO - } 11:53:12 INFO - Finding installer, test and symbols from parent task. 11:53:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AY3aMVQ3RDKQYNb7X1J1dQ'}, attempt #1 11:53:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AY3aMVQ3RDKQYNb7X1J1dQ'}, attempt #1 11:53:13 INFO - Task dependencies: euJRTGk7QEaL4RhTVUUjhA 11:53:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA'}, attempt #1 11:53:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.tar.bz2 11:53:13 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.test_packages.json 11:53:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.crashreporter-symbols.zip 11:53:13 INFO - Running post-action listener: _resource_record_post_action 11:53:13 INFO - [mozharness: 2017-09-03 18:53:13.702707Z] Finished read-buildbot-config step (success) 11:53:13 INFO - [mozharness: 2017-09-03 18:53:13.702924Z] Running download-and-extract step. 11:53:13 INFO - Running pre-action listener: _resource_record_pre_action 11:53:13 INFO - Running main action method: download_and_extract 11:53:13 INFO - mkdir: /builds/slave/test/build/tests 11:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:13 INFO - trying https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.test_packages.json 11:53:13 INFO - Downloading https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:53:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:53:14 INFO - Downloaded 989 bytes. 11:53:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:53:14 INFO - Using the following test package requirements: 11:53:14 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:53:14 INFO - u'common': [u'target.common.tests.zip'], 11:53:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:53:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:53:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:53:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:53:14 INFO - u'mozbase': [u'target.common.tests.zip'], 11:53:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:53:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:53:14 INFO - u'web-platform': [u'target.common.tests.zip', 11:53:14 INFO - u'target.web-platform.tests.tar.gz'], 11:53:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:53:14 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:53:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.common.tests.zip 11:53:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:53:14 INFO - Fetch https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.common.tests.zip into memory 11:53:15 INFO - Content-Length response header: 55686178 11:53:15 INFO - Bytes received: 55686178 11:53:27 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:53:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.common.tests.zip 11:53:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:53:27 INFO - Fetch https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.common.tests.zip into memory 11:53:28 INFO - Content-Length response header: 55686178 11:53:28 INFO - Bytes received: 55686178 11:53:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.talos.tests.zip 11:53:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:53:41 INFO - Fetch https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.talos.tests.zip into memory 11:53:42 INFO - Content-Length response header: 14496650 11:53:42 INFO - Bytes received: 14496650 11:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:42 INFO - trying https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.tar.bz2 11:53:42 INFO - Downloading https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:53:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:53:43 INFO - Downloaded 63445130 bytes. 11:53:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.tar.bz2 11:53:43 INFO - mkdir: /builds/slave/test/properties 11:53:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:53:43 INFO - Writing to file /builds/slave/test/properties/build_url 11:53:43 INFO - Contents: 11:53:43 INFO - build_url:https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.tar.bz2 11:53:43 INFO - Running post-action listener: _resource_record_post_action 11:53:43 INFO - Running post-action listener: find_tests_for_verification 11:53:43 INFO - Running post-action listener: set_extra_try_arguments 11:53:43 INFO - [mozharness: 2017-09-03 18:53:43.685078Z] Finished download-and-extract step (success) 11:53:43 INFO - [mozharness: 2017-09-03 18:53:43.685201Z] Running populate-webroot step. 11:53:43 INFO - Running pre-action listener: _resource_record_pre_action 11:53:43 INFO - Running main action method: populate_webroot 11:53:43 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:53:43 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:53:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:53:43 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:53:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:53:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:43 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:53:43 INFO - 'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:53:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:53:43 INFO - 'tests': ['damp', 'tps']}, 11:53:43 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:53:43 INFO - 'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['damp', 'tps']}, 11:53:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:53:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:53:43 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:53:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:53:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:53:43 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:53:43 INFO - 'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:53:43 INFO - 'other-e10s': {'tests': ['a11yr', 11:53:43 INFO - 'ts_paint', 11:53:43 INFO - 'tpaint', 11:53:43 INFO - 'sessionrestore', 11:53:43 INFO - 'sessionrestore_many_windows', 11:53:43 INFO - 'sessionrestore_no_auto_restore', 11:53:43 INFO - 'tabpaint']}, 11:53:43 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['a11yr', 11:53:43 INFO - 'ts_paint', 11:53:43 INFO - 'tpaint', 11:53:43 INFO - 'sessionrestore', 11:53:43 INFO - 'sessionrestore_many_windows', 11:53:43 INFO - 'sessionrestore_no_auto_restore', 11:53:43 INFO - 'tabpaint']}, 11:53:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:53:43 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:53:43 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['bloom_basic_singleton']}, 11:53:43 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['bloom_basic']}, 11:53:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:53:43 INFO - 'tsvgr_opacity', 11:53:43 INFO - 'tart', 11:53:43 INFO - 'tscrollx', 11:53:43 INFO - 'tsvg_static']}, 11:53:43 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['tsvgx', 11:53:43 INFO - 'tsvgr_opacity', 11:53:43 INFO - 'tart', 11:53:43 INFO - 'tscrollx', 11:53:43 INFO - 'tsvg_static']}, 11:53:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:53:43 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:53:43 INFO - 'talos_options': ['--enable-stylo'], 11:53:43 INFO - 'tests': ['tp5o']}, 11:53:43 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:43 INFO - 'talos_options': ['--mitmproxy', 11:53:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:43 INFO - '--firstNonBlankPaint'], 11:53:43 INFO - 'tests': ['tp6_google', 11:53:43 INFO - 'tp6_youtube', 11:53:43 INFO - 'tp6_amazon', 11:53:43 INFO - 'tp6_facebook']}, 11:53:43 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:43 INFO - 'talos_options': ['--enable-stylo', 11:53:43 INFO - '--mitmproxy', 11:53:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:43 INFO - '--firstNonBlankPaint'], 11:53:43 INFO - 'tests': ['tp6_google', 11:53:43 INFO - 'tp6_youtube', 11:53:43 INFO - 'tp6_amazon', 11:53:43 INFO - 'tp6_facebook']}, 11:53:43 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:53:43 INFO - 'talos_options': ['--enable-stylo', 11:53:43 INFO - '--stylo-threads=1', 11:53:43 INFO - '--mitmproxy', 11:53:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:53:43 INFO - '--firstNonBlankPaint'], 11:53:43 INFO - 'tests': ['tp6_google', 11:53:43 INFO - 'tp6_youtube', 11:53:43 INFO - 'tp6_amazon', 11:53:43 INFO - 'tp6_facebook']}, 11:53:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:53:43 INFO - 'talos_options': ['--xperf_path', 11:53:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:43 INFO - 'tests': ['tp5n']}, 11:53:43 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:53:43 INFO - 'talos_options': ['--enable-stylo', 11:53:43 INFO - '--xperf_path', 11:53:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:53:43 INFO - 'tests': ['tp5n']}}} 11:53:43 INFO - Running post-action listener: _resource_record_post_action 11:53:43 INFO - [mozharness: 2017-09-03 18:53:43.699692Z] Finished populate-webroot step (success) 11:53:43 INFO - [mozharness: 2017-09-03 18:53:43.699781Z] Running create-virtualenv step. 11:53:43 INFO - Running pre-action listener: _resource_record_pre_action 11:53:43 INFO - Running main action method: create_virtualenv 11:53:43 INFO - Creating virtualenv /builds/slave/test/build/venv 11:53:43 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:53:43 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:53:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:53:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:53:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:53:44 INFO - Installing distribute.............................................................................................................................................................................................done. 11:53:44 INFO - Installing pip...............done. 11:53:44 INFO - Return code: 0 11:53:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:53:44 INFO - Reading from file tmpfile_stdout 11:53:44 INFO - Output received: 11:53:44 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:53:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:53:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:45 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 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:53:45 INFO - Using env: {'DISPLAY': ':0', 11:53:45 INFO - 'HOME': '/home/cltbld', 11:53:45 INFO - 'LANG': 'en_US.UTF-8', 11:53:45 INFO - 'LANGUAGE': 'en_US:en', 11:53:45 INFO - 'LOGNAME': 'cltbld', 11:53:45 INFO - 'MAIL': '/var/mail/cltbld', 11:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:45 INFO - 'MOZ_NO_REMOTE': '1', 11:53:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:45 INFO - 'NO_EM_RESTART': '1', 11:53:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:45 INFO - 'PWD': '/builds/slave/test', 11:53:45 INFO - 'SHELL': '/bin/bash', 11:53:45 INFO - 'SHLVL': '1', 11:53:45 INFO - 'TERM': 'linux', 11:53:45 INFO - 'TMOUT': '86400', 11:53:45 INFO - 'USER': 'cltbld', 11:53:45 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:45 INFO - '_': '/tools/buildbot/bin/python'} 11:53:46 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:53:46 INFO - Downloading/unpacking pip>=1.5 11:53:46 INFO - Running setup.py egg_info for package pip 11:53:46 INFO - warning: no files found matching 'pip/cacert.pem' 11:53:46 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:53:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:53:46 INFO - Installing collected packages: pip 11:53:46 INFO - Found existing installation: pip 1.1 11:53:46 INFO - Uninstalling pip: 11:53:46 INFO - Successfully uninstalled pip 11:53:46 INFO - Running setup.py install for pip 11:53:46 INFO - warning: no files found matching 'pip/cacert.pem' 11:53:46 INFO - warning: no files found matching '*.html' under directory 'docs' 11:53:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:53:46 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:53:46 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:53:46 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:53:46 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:53:46 INFO - Successfully installed pip 11:53:46 INFO - Cleaning up... 11:53:46 INFO - Return code: 0 11:53:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:46 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 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:53:46 INFO - Using env: {'DISPLAY': ':0', 11:53:46 INFO - 'HOME': '/home/cltbld', 11:53:46 INFO - 'LANG': 'en_US.UTF-8', 11:53:46 INFO - 'LANGUAGE': 'en_US:en', 11:53:46 INFO - 'LOGNAME': 'cltbld', 11:53:46 INFO - 'MAIL': '/var/mail/cltbld', 11:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:46 INFO - 'MOZ_NO_REMOTE': '1', 11:53:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:46 INFO - 'NO_EM_RESTART': '1', 11:53:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:46 INFO - 'PWD': '/builds/slave/test', 11:53:46 INFO - 'SHELL': '/bin/bash', 11:53:46 INFO - 'SHLVL': '1', 11:53:46 INFO - 'TERM': 'linux', 11:53:46 INFO - 'TMOUT': '86400', 11:53:46 INFO - 'USER': 'cltbld', 11:53:46 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:46 INFO - '_': '/tools/buildbot/bin/python'} 11:53:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:46 INFO - Downloading/unpacking psutil>=3.1.1 11:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:53:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:47 INFO - Installing collected packages: psutil 11:53:47 INFO - Running setup.py install for psutil 11:53:47 INFO - building 'psutil._psutil_linux' extension 11:53:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:53:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:53:49 INFO - building 'psutil._psutil_posix' extension 11:53:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:53:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:53:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:53:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:53:49 INFO - Successfully installed psutil 11:53:49 INFO - Cleaning up... 11:53:49 INFO - Return code: 0 11:53:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:53:49 INFO - Using env: {'DISPLAY': ':0', 11:53:49 INFO - 'HOME': '/home/cltbld', 11:53:49 INFO - 'LANG': 'en_US.UTF-8', 11:53:49 INFO - 'LANGUAGE': 'en_US:en', 11:53:49 INFO - 'LOGNAME': 'cltbld', 11:53:49 INFO - 'MAIL': '/var/mail/cltbld', 11:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:49 INFO - 'MOZ_NO_REMOTE': '1', 11:53:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:49 INFO - 'NO_EM_RESTART': '1', 11:53:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:49 INFO - 'PWD': '/builds/slave/test', 11:53:49 INFO - 'SHELL': '/bin/bash', 11:53:49 INFO - 'SHLVL': '1', 11:53:49 INFO - 'TERM': 'linux', 11:53:49 INFO - 'TMOUT': '86400', 11:53:49 INFO - 'USER': 'cltbld', 11:53:49 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:49 INFO - '_': '/tools/buildbot/bin/python'} 11:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:53:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:53:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:53:50 INFO - Installing collected packages: mozsystemmonitor 11:53:50 INFO - Running setup.py install for mozsystemmonitor 11:53:50 INFO - Successfully installed mozsystemmonitor 11:53:50 INFO - Cleaning up... 11:53:50 INFO - Return code: 0 11:53:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:53:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:53:50 INFO - Using env: {'DISPLAY': ':0', 11:53:50 INFO - 'HOME': '/home/cltbld', 11:53:50 INFO - 'LANG': 'en_US.UTF-8', 11:53:50 INFO - 'LANGUAGE': 'en_US:en', 11:53:50 INFO - 'LOGNAME': 'cltbld', 11:53:50 INFO - 'MAIL': '/var/mail/cltbld', 11:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:50 INFO - 'MOZ_NO_REMOTE': '1', 11:53:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:50 INFO - 'NO_EM_RESTART': '1', 11:53:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:50 INFO - 'PWD': '/builds/slave/test', 11:53:50 INFO - 'SHELL': '/bin/bash', 11:53:50 INFO - 'SHLVL': '1', 11:53:50 INFO - 'TERM': 'linux', 11:53:50 INFO - 'TMOUT': '86400', 11:53:50 INFO - 'USER': 'cltbld', 11:53:50 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:50 INFO - '_': '/tools/buildbot/bin/python'} 11:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:50 INFO - Downloading/unpacking jsonschema==2.5.1 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:53:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:52 INFO - Downloading functools32-3.2.3-2.tar.gz 11:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:53:52 INFO - warning: no files found matching '*.txt' 11:53:52 INFO - no previously-included directories found matching 'build' 11:53:52 INFO - no previously-included directories found matching 'dist' 11:53:52 INFO - no previously-included directories found matching '.git*' 11:53:52 INFO - Installing collected packages: jsonschema, functools32 11:53:52 INFO - Running setup.py install for functools32 11:53:52 INFO - warning: no files found matching '*.txt' 11:53:52 INFO - no previously-included directories found matching 'build' 11:53:52 INFO - no previously-included directories found matching 'dist' 11:53:52 INFO - no previously-included directories found matching '.git*' 11:53:52 INFO - Successfully installed jsonschema functools32 11:53:52 INFO - Cleaning up... 11:53:52 INFO - Return code: 0 11:53:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:53:52 INFO - Using env: {'DISPLAY': ':0', 11:53:52 INFO - 'HOME': '/home/cltbld', 11:53:52 INFO - 'LANG': 'en_US.UTF-8', 11:53:52 INFO - 'LANGUAGE': 'en_US:en', 11:53:52 INFO - 'LOGNAME': 'cltbld', 11:53:52 INFO - 'MAIL': '/var/mail/cltbld', 11:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:52 INFO - 'MOZ_NO_REMOTE': '1', 11:53:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:52 INFO - 'NO_EM_RESTART': '1', 11:53:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:52 INFO - 'PWD': '/builds/slave/test', 11:53:52 INFO - 'SHELL': '/bin/bash', 11:53:52 INFO - 'SHLVL': '1', 11:53:52 INFO - 'TERM': 'linux', 11:53:52 INFO - 'TMOUT': '86400', 11:53:52 INFO - 'USER': 'cltbld', 11:53:52 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:52 INFO - '_': '/tools/buildbot/bin/python'} 11:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:53:52 INFO - Cleaning up... 11:53:52 INFO - Return code: 0 11:53:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:53:52 INFO - Using env: {'DISPLAY': ':0', 11:53:52 INFO - 'HOME': '/home/cltbld', 11:53:52 INFO - 'LANG': 'en_US.UTF-8', 11:53:52 INFO - 'LANGUAGE': 'en_US:en', 11:53:52 INFO - 'LOGNAME': 'cltbld', 11:53:52 INFO - 'MAIL': '/var/mail/cltbld', 11:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:52 INFO - 'MOZ_NO_REMOTE': '1', 11:53:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:52 INFO - 'NO_EM_RESTART': '1', 11:53:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:52 INFO - 'PWD': '/builds/slave/test', 11:53:52 INFO - 'SHELL': '/bin/bash', 11:53:52 INFO - 'SHLVL': '1', 11:53:52 INFO - 'TERM': 'linux', 11:53:52 INFO - 'TMOUT': '86400', 11:53:52 INFO - 'USER': 'cltbld', 11:53:52 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:52 INFO - '_': '/tools/buildbot/bin/python'} 11:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:52 INFO - Downloading/unpacking blobuploader==1.2.4 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:54 INFO - Downloading blobuploader-1.2.4.tar.gz 11:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:53:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:53:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:53:54 INFO - Downloading docopt-0.6.1.tar.gz 11:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:53:54 INFO - Installing collected packages: blobuploader, requests, docopt 11:53:54 INFO - Running setup.py install for blobuploader 11:53:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:53:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:53:54 INFO - Running setup.py install for requests 11:53:55 INFO - Running setup.py install for docopt 11:53:55 INFO - Successfully installed blobuploader requests docopt 11:53:55 INFO - Cleaning up... 11:53:55 INFO - Return code: 0 11:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:53:55 INFO - Using env: {'DISPLAY': ':0', 11:53:55 INFO - 'HOME': '/home/cltbld', 11:53:55 INFO - 'LANG': 'en_US.UTF-8', 11:53:55 INFO - 'LANGUAGE': 'en_US:en', 11:53:55 INFO - 'LOGNAME': 'cltbld', 11:53:55 INFO - 'MAIL': '/var/mail/cltbld', 11:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:55 INFO - 'MOZ_NO_REMOTE': '1', 11:53:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:55 INFO - 'NO_EM_RESTART': '1', 11:53:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:55 INFO - 'PWD': '/builds/slave/test', 11:53:55 INFO - 'SHELL': '/bin/bash', 11:53:55 INFO - 'SHLVL': '1', 11:53:55 INFO - 'TERM': 'linux', 11:53:55 INFO - 'TMOUT': '86400', 11:53:55 INFO - 'USER': 'cltbld', 11:53:55 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:55 INFO - '_': '/tools/buildbot/bin/python'} 11:53:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:53:55 INFO - Running setup.py (path:/tmp/pip-Dehqcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:53:55 INFO - Running setup.py (path:/tmp/pip-tAV_BK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:53:55 INFO - Running setup.py (path:/tmp/pip-nWMu79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:53:55 INFO - Running setup.py (path:/tmp/pip-gwBzQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:53:55 INFO - Running setup.py (path:/tmp/pip-_e9OOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:53:55 INFO - Running setup.py (path:/tmp/pip-_7tWoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:53:55 INFO - Running setup.py (path:/tmp/pip-X7qu8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:53:55 INFO - Running setup.py (path:/tmp/pip-Wy6nus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:53:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:53:55 INFO - Running setup.py (path:/tmp/pip-DL1O_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:53:56 INFO - Running setup.py (path:/tmp/pip-jbun5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:53:56 INFO - Running setup.py (path:/tmp/pip-KLOOeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:53:56 INFO - Running setup.py (path:/tmp/pip-jSFr4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:53:56 INFO - Running setup.py (path:/tmp/pip-OEja4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:53:56 INFO - Running setup.py (path:/tmp/pip-9LfrM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:56 INFO - Running setup.py (path:/tmp/pip-CnJyyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:53:56 INFO - Running setup.py (path:/tmp/pip-eNg3PA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:53:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:53:56 INFO - Running setup.py (path:/tmp/pip-OPF2Oe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:53:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:53:56 INFO - Running setup.py install for manifestparser 11:53:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:53:56 INFO - Running setup.py install for mozcrash 11:53:56 INFO - Running setup.py install for mozdebug 11:53:56 INFO - Running setup.py install for mozdevice 11:53:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Running setup.py install for mozfile 11:53:57 INFO - Running setup.py install for mozhttpd 11:53:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Running setup.py install for mozinfo 11:53:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Running setup.py install for mozInstall 11:53:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Running setup.py install for mozleak 11:53:57 INFO - Running setup.py install for mozlog 11:53:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Running setup.py install for moznetwork 11:53:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Running setup.py install for mozprocess 11:53:57 INFO - Running setup.py install for mozprofile 11:53:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:53:57 INFO - Running setup.py install for mozrunner 11:53:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:53:58 INFO - Running setup.py install for mozscreenshot 11:53:58 INFO - Running setup.py install for moztest 11:53:58 INFO - Running setup.py install for mozversion 11:53:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:53:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:53:58 INFO - Cleaning up... 11:53:58 INFO - Return code: 0 11:53:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:53:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:53:58 INFO - Using env: {'DISPLAY': ':0', 11:53:58 INFO - 'HOME': '/home/cltbld', 11:53:58 INFO - 'LANG': 'en_US.UTF-8', 11:53:58 INFO - 'LANGUAGE': 'en_US:en', 11:53:58 INFO - 'LOGNAME': 'cltbld', 11:53:58 INFO - 'MAIL': '/var/mail/cltbld', 11:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:53:58 INFO - 'MOZ_NO_REMOTE': '1', 11:53:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:53:58 INFO - 'NO_EM_RESTART': '1', 11:53:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:53:58 INFO - 'PWD': '/builds/slave/test', 11:53:58 INFO - 'SHELL': '/bin/bash', 11:53:58 INFO - 'SHLVL': '1', 11:53:58 INFO - 'TERM': 'linux', 11:53:58 INFO - 'TMOUT': '86400', 11:53:58 INFO - 'USER': 'cltbld', 11:53:58 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:53:58 INFO - '_': '/tools/buildbot/bin/python'} 11:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:53:58 INFO - Running setup.py (path:/tmp/pip-RF44jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:53:58 INFO - Running setup.py (path:/tmp/pip-Cp9IHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:53:58 INFO - Running setup.py (path:/tmp/pip-e_m7Hv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:53:58 INFO - Running setup.py (path:/tmp/pip-5x1pSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:53:58 INFO - Running setup.py (path:/tmp/pip-L_Qkj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:53:58 INFO - Running setup.py (path:/tmp/pip-evFpQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:53:59 INFO - Running setup.py (path:/tmp/pip-RXO1Jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:53:59 INFO - Running setup.py (path:/tmp/pip-im4hG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:53:59 INFO - Running setup.py (path:/tmp/pip-U2W1O2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:53:59 INFO - Running setup.py (path:/tmp/pip-WwMSoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:53:59 INFO - Running setup.py (path:/tmp/pip-vW3DRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:53:59 INFO - Running setup.py (path:/tmp/pip-oLos5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:53:59 INFO - Running setup.py (path:/tmp/pip-J97KgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:53:59 INFO - Running setup.py (path:/tmp/pip-ZxuDHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:59 INFO - Running setup.py (path:/tmp/pip-Br5zAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:53:59 INFO - Running setup.py (path:/tmp/pip-wLOVgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:53:59 INFO - Running setup.py (path:/tmp/pip-uJ_mhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:53:59 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:53:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:53: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 11:53: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 11:53: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 11:53: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 11:54:00 INFO - Downloading blessings-1.6.tar.gz 11:54:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:54:00 INFO - Installing collected packages: blessings 11:54:00 INFO - Running setup.py install for blessings 11:54:01 INFO - Successfully installed blessings 11:54:01 INFO - Cleaning up... 11:54:01 INFO - Return code: 0 11:54:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:54:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:01 INFO - Reading from file tmpfile_stdout 11:54:01 INFO - Current package versions: 11:54:01 INFO - argparse == 1.2.1 11:54:01 INFO - blessings == 1.6 11:54:01 INFO - blobuploader == 1.2.4 11:54:01 INFO - docopt == 0.6.1 11:54:01 INFO - functools32 == 3.2.3-2 11:54:01 INFO - jsonschema == 2.5.1 11:54:01 INFO - manifestparser == 1.1 11:54:01 INFO - mozInstall == 1.13 11:54:01 INFO - mozcrash == 1.0 11:54:01 INFO - mozdebug == 0.1 11:54:01 INFO - mozdevice == 0.51 11:54:01 INFO - mozfile == 1.2 11:54:01 INFO - mozhttpd == 0.7 11:54:01 INFO - mozinfo == 0.10 11:54:01 INFO - mozleak == 0.1 11:54:01 INFO - mozlog == 3.5 11:54:01 INFO - moznetwork == 0.27 11:54:01 INFO - mozprocess == 0.25 11:54:01 INFO - mozprofile == 0.28 11:54:01 INFO - mozrunner == 6.13 11:54:01 INFO - mozscreenshot == 0.1 11:54:01 INFO - mozsystemmonitor == 0.3 11:54:01 INFO - moztest == 0.8 11:54:01 INFO - mozversion == 1.4 11:54:01 INFO - psutil == 3.1.1 11:54:01 INFO - requests == 1.2.3 11:54:01 INFO - wsgiref == 0.1.2 11:54:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:54:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:54:01 INFO - Using env: {'DISPLAY': ':0', 11:54:01 INFO - 'HOME': '/home/cltbld', 11:54:01 INFO - 'LANG': 'en_US.UTF-8', 11:54:01 INFO - 'LANGUAGE': 'en_US:en', 11:54:01 INFO - 'LOGNAME': 'cltbld', 11:54:01 INFO - 'MAIL': '/var/mail/cltbld', 11:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:01 INFO - 'MOZ_NO_REMOTE': '1', 11:54:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:01 INFO - 'NO_EM_RESTART': '1', 11:54:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:01 INFO - 'PWD': '/builds/slave/test', 11:54:01 INFO - 'SHELL': '/bin/bash', 11:54:01 INFO - 'SHLVL': '1', 11:54:01 INFO - 'TERM': 'linux', 11:54:01 INFO - 'TMOUT': '86400', 11:54:01 INFO - 'USER': 'cltbld', 11:54:01 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:01 INFO - '_': '/tools/buildbot/bin/python'} 11:54:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:54:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:54:01 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:54:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:54:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:54:02 INFO - Installing collected packages: simplejson 11:54:02 INFO - Running setup.py install for simplejson 11:54:02 INFO - building 'simplejson._speedups' extension 11:54:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:54:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:54:03 INFO - Successfully installed simplejson 11:54:03 INFO - Cleaning up... 11:54:03 INFO - Return code: 0 11:54:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:54: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')]} 11:54:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:54: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')]} 11:54:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:54: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdfc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1168a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:54: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'] in /builds/slave/test/build 11:54: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 11:54:03 INFO - Using env: {'DISPLAY': ':0', 11:54:03 INFO - 'HOME': '/home/cltbld', 11:54:03 INFO - 'LANG': 'en_US.UTF-8', 11:54:03 INFO - 'LANGUAGE': 'en_US:en', 11:54:03 INFO - 'LOGNAME': 'cltbld', 11:54:03 INFO - 'MAIL': '/var/mail/cltbld', 11:54:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:03 INFO - 'MOZ_NO_REMOTE': '1', 11:54:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:03 INFO - 'NO_EM_RESTART': '1', 11:54:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:03 INFO - 'PWD': '/builds/slave/test', 11:54:03 INFO - 'SHELL': '/bin/bash', 11:54:03 INFO - 'SHLVL': '1', 11:54:03 INFO - 'TERM': 'linux', 11:54:03 INFO - 'TMOUT': '86400', 11:54:03 INFO - 'USER': 'cltbld', 11:54:03 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:54:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:03 INFO - '_': '/tools/buildbot/bin/python'} 11:54:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:54:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:54:04 INFO - Cleaning up... 11:54:04 INFO - Return code: 0 11:54:04 INFO - Running post-action listener: _resource_record_post_action 11:54:04 INFO - Running post-action listener: _start_resource_monitoring 11:54:04 INFO - Starting resource monitoring. 11:54:04 INFO - [mozharness: 2017-09-03 18:54:04.105094Z] Finished create-virtualenv step (success) 11:54:04 INFO - [mozharness: 2017-09-03 18:54:04.106158Z] Running install step. 11:54:04 INFO - Running pre-action listener: _resource_record_pre_action 11:54:04 INFO - Running main action method: install 11:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:54:04 INFO - Reading from file tmpfile_stdout 11:54:04 INFO - Detecting whether we're running mozinstall >=1.0... 11:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:54:04 INFO - Reading from file tmpfile_stdout 11:54:04 INFO - Output received: 11:54:04 INFO - Usage: mozinstall [options] installer 11:54:04 INFO - Options: 11:54:04 INFO - -h, --help show this help message and exit 11:54:04 INFO - -d DEST, --destination=DEST 11:54:04 INFO - Directory to install application into. [default: 11:54:04 INFO - "/builds/slave/test"] 11:54:04 INFO - --app=APP Application being installed. [default: firefox] 11:54:04 INFO - mkdir: /builds/slave/test/build/application 11:54:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:54:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:54:20 INFO - Reading from file tmpfile_stdout 11:54:20 INFO - Output received: 11:54:20 INFO - /builds/slave/test/build/application/firefox/firefox 11:54:20 INFO - Running post-action listener: _resource_record_post_action 11:54:20 INFO - [mozharness: 2017-09-03 18:54:20.803201Z] Finished install step (success) 11:54:20 INFO - [mozharness: 2017-09-03 18:54:20.803348Z] Running setup-mitmproxy step. 11:54:20 INFO - Running pre-action listener: _resource_record_pre_action 11:54:20 INFO - Running main action method: setup_mitmproxy 11:54:20 INFO - Skipping: mitmproxy is not required 11:54:20 INFO - Running post-action listener: _resource_record_post_action 11:54:20 INFO - [mozharness: 2017-09-03 18:54:20.803875Z] Finished setup-mitmproxy step (success) 11:54:20 INFO - [mozharness: 2017-09-03 18:54:20.803979Z] Running run-tests step. 11:54:20 INFO - Running pre-action listener: _resource_record_pre_action 11:54:20 INFO - Running pre-action listener: _set_gcov_prefix 11:54:20 INFO - Running main action method: run_tests 11:54:20 WARNING - Try message not found. 11:54:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:54:20 INFO - Python 2.7.3 11:54:20 INFO - Return code: 0 11:54:20 INFO - grabbing minidump binary from tooltool 11:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:54:20 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 0x116c090>, '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 0x116cd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1166f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:54:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:54:20 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:54:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:54:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:54:20 INFO - Return code: 0 11:54:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:54:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:54:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:54:20 INFO - ENV: RUST_BACKTRACE is now full 11:54:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:54:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:54:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:54:20 INFO - Using env: {'DISPLAY': ':0', 11:54:20 INFO - 'HOME': '/home/cltbld', 11:54:20 INFO - 'LANG': 'en_US.UTF-8', 11:54:20 INFO - 'LANGUAGE': 'en_US:en', 11:54:20 INFO - 'LOGNAME': 'cltbld', 11:54:20 INFO - 'MAIL': '/var/mail/cltbld', 11:54:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:54:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:20 INFO - 'MOZ_NO_REMOTE': '1', 11:54:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:20 INFO - 'NO_EM_RESTART': '1', 11:54:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:20 INFO - 'PWD': '/builds/slave/test', 11:54:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:20 INFO - 'RUST_BACKTRACE': 'full', 11:54:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:54:20 INFO - 'SHELL': '/bin/bash', 11:54:20 INFO - 'SHLVL': '1', 11:54:20 INFO - 'TERM': 'linux', 11:54:20 INFO - 'TMOUT': '86400', 11:54:20 INFO - 'USER': 'cltbld', 11:54:20 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:54:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:20 INFO - '_': '/tools/buildbot/bin/python'} 11:54:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:54:21 INFO - mozversion application_buildid: 20170903173751 11:54:21 INFO - mozversion application_changeset: 0afabd3e5c27b0036517b96eecb1f8553d027179 11:54:21 INFO - mozversion application_display_name: Nightly 11:54:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:54:21 INFO - mozversion application_name: Firefox 11:54:21 INFO - mozversion application_remotingname: firefox 11:54:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:54:21 INFO - mozversion application_vendor: Mozilla 11:54:21 INFO - mozversion application_version: 57.0a1 11:54:21 INFO - mozversion platform_buildid: 20170903173751 11:54:21 INFO - mozversion platform_changeset: 0afabd3e5c27b0036517b96eecb1f8553d027179 11:54:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:54:21 INFO - mozversion platform_version: 57.0a1 11:54:21 INFO - using testdate: 1504464861 11:54:21 INFO - actual date: 1504464861 11:54:21 INFO - starting webserver on 'localhost:38996' 11:54:21 INFO - SUITE-START | Running 2 tests 11:54:21 INFO - TEST-START | dromaeo_css 11:54:21 INFO - Initialising browser for dromaeo_css test... 11:54:21 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38996/getInfo.html -profile /tmp/tmpASQ0Lv/profile 11:54:21 INFO - TEST-INFO | started process 3552 (/builds/slave/test/build/application/firefox/firefox http://localhost:38996/getInfo.html) 11:54:27 INFO - TEST-INFO | 3552: exit 0 11:54:28 INFO - Browser initialized. 11:54:28 INFO - Running cycle 1/1 for dromaeo_css test... 11:54:28 INFO - Using env: {'DISPLAY': ':0', 11:54:28 INFO - 'HOME': '/home/cltbld', 11:54:28 INFO - 'JSGC_DISABLE_POISONING': '1', 11:54:28 INFO - 'LANG': 'en_US.UTF-8', 11:54:28 INFO - 'LANGUAGE': 'en_US:en', 11:54:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:54:28 INFO - 'LOGNAME': 'cltbld', 11:54:28 INFO - 'MAIL': '/var/mail/cltbld', 11:54:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:54:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:54:28 INFO - 'MOZ_CRASHREPORTER': '1', 11:54:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:54:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:54:28 INFO - 'MOZ_NO_REMOTE': '1', 11:54:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:54:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:54:28 INFO - 'NO_EM_RESTART': '1', 11:54:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:54:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:54:28 INFO - 'PWD': '/builds/slave/test', 11:54:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:54:28 INFO - 'RUST_BACKTRACE': 'full', 11:54:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:54:28 INFO - 'SHELL': '/bin/bash', 11:54:28 INFO - 'SHLVL': '1', 11:54:28 INFO - 'TERM': 'linux', 11:54:28 INFO - 'TMOUT': '86400', 11:54:28 INFO - 'USER': 'cltbld', 11:54:28 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 11:54:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:54:28 INFO - '_': '/tools/buildbot/bin/python'} 11:54:28 INFO - TEST-INFO | started process 3739 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpASQ0Lv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:54:28 INFO - PID 3739 | 11:54:28 INFO - PID 3739 | (/builds/slave/test/build/application/firefox/firefox:3791): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:54:28 INFO - PID 3739 | 11:54:39 INFO - PID 3739 | RSS: Main: 161841152 11:54:39 INFO - PID 3739 | 11:57:39 INFO - PID 3739 | Cycle 1(1): loaded http://localhost:38996/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38996/tests/dromaeo/cssquery-ext.html) 11:57:40 INFO - PID 3739 | RSS: Main: 163340288 11:57:40 INFO - PID 3739 | 12:00:41 INFO - PID 3739 | Cycle 1(1): loaded http://localhost:38996/tests/dromaeo/cssquery-ext.html (next: http://localhost:38996/tests/dromaeo/cssquery-jquery.html) 12:00:42 INFO - PID 3739 | RSS: Main: 161050624 12:00:42 INFO - PID 3739 | 12:03:43 INFO - PID 3739 | Cycle 1(1): loaded http://localhost:38996/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38996/tests/dromaeo/cssquery-mootools.html) 12:03:44 INFO - PID 3739 | RSS: Main: 163594240 12:03:44 INFO - PID 3739 | 12:06:46 INFO - PID 3739 | Cycle 1(1): loaded http://localhost:38996/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38996/tests/dromaeo/cssquery-prototype.html) 12:06:46 INFO - PID 3739 | RSS: Main: 161656832 12:06:46 INFO - PID 3739 | 12:09:49 INFO - PID 3739 | Cycle 1(1): loaded http://localhost:38996/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38996/tests/dromaeo/cssquery-yui.html) 12:09:50 INFO - PID 3739 | RSS: Main: 163639296 12:09:50 INFO - PID 3739 | 12:12:52 INFO - PID 3739 | Cycle 1(1): loaded http://localhost:38996/tests/dromaeo/cssquery-yui.html (next: http://localhost:38996/tests/dromaeo/cssquery-dojo.html) 12:12:52 INFO - PID 3739 | RSS: Main: 166522880 12:12:52 INFO - PID 3739 | 12:12:52 INFO - PID 3739 | __start_tp_report 12:12:52 INFO - PID 3739 | _x_x_mozilla_page_load 12:12:52 INFO - PID 3739 | _x_x_mozilla_page_load_details 12:12:52 INFO - PID 3739 | |i|pagename|runs| 12:12:52 INFO - PID 3739 | |0;dojo.html;3905.9414108788355;3925.0579860833373;3963.980180099099;3969;3979.4627725257083;6759;6857.897131543024;6878.9312106878915;6911.2397636259975;6940;6768.153980752406;6841.486888483359;6841.897371539431;6845.555038922479;6884.173899132111;6110.8472288192925;6118.999999999989;6152.692365381713;6165.876682466348;6173.228346456693;6128.877422451548;6162.198914141178;6187.133801267826;6189.133521307021;6196.78311259106;6225.937740622581;6235.158253635746;6253.937460625404;6271.184745983039;6300.5589608727405;6095.908561371583;6137.202163718733;6162.7226774795245;6189.50483961282;6264.937350626504;5757.366689664133;5970.611910225864;6071.574989750719;6082.726277317487;6109.541784366146;6873.347032031999;6882.03651488792;6928.376446119873;7010.75462358815;7026.894596581055;7482.401407887356;7503.362214211798;7581.620918954085;7593.6203189840835;7610.124835643916;7441.293077157661;7581;7585.203553626845;7585.431092668072;7685;6983.127109111361;6988.545744526589;6994.405475534535;6996.195437524699;7003.439724822053;7516.887246691304;7622.31399174077;7734.651940662684;7737.8452430951065;7747.070351557736;7336.413086953031;7337.633118344062;7424.109106907098;7425.071866016748;7449.180590135081;7319.160794617658;7341.119065712149;7411.481196316314;7425.962870185723;7473.51422157558;7356.558606483628;7459.8508029839095;7510.511816731895;7573.659185336673;7577.280158384945;7458.70165193397;7491.400687944952;7491.475596708287;7577.848443031108;7614.124375696755;6146.938530614726;6249.312575616678;6273.7176827042895;6292.370762923671;6313.3686631336495;5505.119180931032;5514.255575497336;5555.2222688823595;5575.553955683536;5577.693226872561;6308.369163083655;6329.3354197809485;6389.6805159741825;6402.8719425611225;6473.255575608865;7341.449391295568;7358.852822943618;7362.558246505227;7363.300486453778;7373.29953654402;6346.904796428057;6354.078658594571;6360.173177486897;6360.491160707133;6364.745410183634;6609.239937407164;6624.304448032887;6670.7665231716655;6757.763478278237;6759.831004224934;7303.671334789948;7308.013418188477;7308.744193953186;7324.780256592311;7325;7309.305615966475;7423.146338171179;7427.7771666850085;7439.665215065227;7542.245775422633;7167.211606723361;7199;7211.062561866924;7211.314925082109;7220.458465614995;6945.131858517685;7377.926220737724;7404.592747398945;7501.249875012455;7521.360684341892;7488.9625551871895;7503.362214211853;7520.473566850268;7579.962100189464;7601.695932162652;7101.360877521048;7186.281371862772;7187.245339239514;7204.207537171012;7300.342969132591;7186.7125314986815;7248.96375518119;7255.09311336083;7278.526895751759;7281.526700764433;7190.640467976685;7264.418846492374;7279;7279.235680253497;7303.342699157101;7177.354038136384;7178.28217178278;7237.855242895218;7255.2019277880445;7335.853282934205;6896.241413444617;6993.0908981832035;7001.229864714775;7054.329838665319;7126.251743567058 12:12:52 INFO - PID 3739 | |1;ext.html;918.6095909238574;926.7868390270241;944.7826999790049;978.9216862650989;987.8320685483467;42601;43902.34146487805;44327;44437.77781111098;44448.77775611117;40532.59467405325;40734.79632601833;41070.17859642806;41076.38385424221;41201.58798412015;7427.554346739186;7679.116901556309;7687.500312479699;7722.996010518625;7752.5348479091435;7534.171241163468;7537.773866784005;7549.35830454412;7558.886616700754;7561.092668879742;7531.209223031585;7562.508436951608;7576.810579735496;7591;7595.278448547379;7429.517081389719;7540.8114797129965;7546.773596792077;7549;7566.735164269252;6933.895991560131;7039.8592028159665;7062.6821793019435;7063.540869843444;7065.2228254891925;36624.63375366265;36972.815135924146;37103.25793484142;37191.256174876624;37241.068973275615;3586.3185994661194;3606.945895811565;3609.729270304738;3611.2235869287965;3613.7831730096277;29311.267218319714;29496.852515737282;29563.70436295631;29570.556441653393;29694.109176724516;4855;4988.55103040728;5012.373453318335;5032.7735251913755;5040.243963405482;201482;203407;206446;207382;214014;51381.48618513842;51558.22662660063;51765.48234517682;51912.74043629833;52016.479835201164;53216.73391633017;53942.46057539453;53959.19061214085;54013.189802153;54224;14015.50945716895;14177.14937103777;14237.21695306768;14244.359003844851;14322.283885805668;11202.94398528002;11205.94397028026;11216.775664486664;11226.775464490827;11322.490487928064;13601.659958501117;13671.453141874214;13682.247476388695;13706.45174193041;13876.514321998682;5255;5294.523492885659;5332.573394128461;5340.679559226459;5386.811461598825;1077.5743581285349;1089.8964598363302;1116.6482557994207;1130.5308297056795;1131.055568600228;25473.745262547138;25512.744872551408;25812.09657661973;25812.48375032489;25965.480690386088;1184.1296646964474;1189.482575079843;1202.1224506110589;1220.3044264768996;1226.068188176974;13391.866081339062;13408.396622152026;13463.2550578215;13568.796468052988;13588;246943;247042;247843;247937;248049;57910.42089579134;58074.12888806671;58221.70889145527;58279.708601456725;58347.4165258342;1491.8284397294233;1512.8260250071382;1513.175319450902;1522.9771553426485;1543.3286520363454;25018.982536067226;25147.622785657863;25165;25199.108632870866;25340.239792806246;41344.1731165381;41423.7928810354;41523.58476415197;41549.584504155784;41677;227161;228965;229060;229261;229893;36790.8160459196;38592.42111368388;38788.50903418706;39127;39156.412653809646;48789.268160978325;50041;50075.248871267686;50408;50497.99004019972;36796.080097997765;36994.63005369912;37045.81477092597;37219.62780372162;37340;36039.63960360363;36441.81779091088;36676.63323366732;36799;36896;42579.57420425756;43230;43435.56564434315;43438;43859.78070109629;35036.64963350334;35088.824555878076;35161.648383515836;35348.46977295394;35552.46671299984;26547.867260663574;26728.732712672623;27095.59356609613;27439.176824695292;27527.31181720473 12:12:52 INFO - PID 3739 | |2;jquery.html;585.9765609375626;603.4237303375277;605.8519106293572;610.94306849151;617.580045569013;23039;23047.193348232817;23956.401089972736;23978.640320395207;23996.040158393665;26693.332666683295;27107.86446067767;27197.86401067997;27253;27283;6749.696263668135;6896.862062758742;6902.171739391255;6911.447084233275;6923.238443771181;6697.464202863784;6778;6840.31596840317;6936.098307220054;6995.860082798341;6942.166939967211;6948.548344357626;6949.791506254795;6951.061606683109;6952.09622749042;6897.689603967833;6940.791776246695;6942.583444993316;6942.687579058929;6942.72229110835;6455.322191169907;6465.418112369863;6466.256380516254;6468.902966455507;6474.061261117159;30158.24604384886;30173.09480715582;30862.22844428885;31053.534196987064;31308.217294567592;2271.931842044725;2315.305408377497;2323.2913961241816;2323.4423738302853;2324.3027091872373;20319.695204571944;20558.897205514026;20576.794232057637;20606.587868242703;20626.381208563766;2393.102586530051;2510.9372265693323;2519.937001574957;2528.014074510942;2538.834975726572;150275;154140;154495;154514.59198928095;154732.2263388687;15564.143972081418;16338.346466141222;16461.917690411472;16465.423710170326;16762.916185418995;25595.104171353913;25655.230343089737;25660.358491037874;25696;25761.74238257631;10590.629327973484;10592.934377796255;10598.576056957789;10786;10826.62106826273;8629.439086459486;9797.216222702167;9803.362781419328;9853.753656158653;10002;8693.739187824376;9800.607975681036;9828.21374290055;9966.651167209258;10121.746956326151;2916.650001999731;2973.405318936221;2974.6876577959433;2984.701529846998;3008.879644814183;1053.9894601054045;1057.117307048623;1078.449990504848;1081.7079388565044;1086.1582273737915;21702.131914723235;21760.34718958434;21896.23363182313;21919.232826850985;22087.447813804785;1120.8710998235308;1121.725177331559;1126.0709914320719;1127.3686735428187;1133.6882357351794;11980.28118312904;12239.43164110149;12307;12346.81479777804;12363.567275145328;664706;683070;685016;687051;692375;136226;138415;138758;139153.30423347818;140231;1254.2223821230896;1283.4481173095658;1293.1852932652369;1315.2437348524636;1319.2414361742037;23238.55873465415;23923.880380597984;24047.398815029763;24180;24190.51618967646;66913.99629005471;66923.66538167278;67459.66270168617;69083.30916690768;70246.94629580458;84904.57547712221;86410.56794716237;86763;87435.56282218803;87471.56264218893;13312.46750129984;13773.104748191336;15392.076475411512;15522.301496432914;16002.199890005686;24232.636510452005;24405.291629585758;24464.388390290384;24625.261242162764;24659.63010554879;19566.510837229183;20028.69956950676;20287.79712202918;20347.389578312675;20378.79621203769;16580;16628.3348666052;16878.57803554921;16886.40897568579;16929.153542322587;20152.798472015093;20224.19103235854;20548.28081017157;20630.17479300871;20647.8967605161;15488.380464781734;15625.687486249984;15658.295376708304;15665.60835979064;15712.842871571595;17676.734848977532;19689.606207876048;20069.29757458482;20075.0966206524;20111.195552178335 12:12:52 INFO - PID 3739 | |3;mootools.html;1385.7228554289143;1458.8249410070787;1460.102037247093;1473.0204414064647;1483.6216764724993;16182.190890455475;16742.49772506824;16779.916100419512;16796.412125575593;16884.66230675387;15841.445549405773;16344.18279086044;17097.743533847002;17106.48680539586;17120.657586848254;2852.9714702852966;2907.8982235621756;2912.0681381957784;2922.4593450211673;2923.8391888446126;2756.2833663247598;2795.077624383948;2832.22113918673;2846.416484620658;2846.615706879576;2763.101991852646;2778.9861050694717;2796.0633187882086;2801.075645037143;2801.6638003439616;2700.891964321425;2722.618833363331;2727.3318037080944;2728.386113124541;2728.8089833711647;2652.2175958092407;2657.827241229314;2668.85321307328;2669.145873320538;2686.355274734069;16194.676106477804;16245.106519141442;16373.59066023347;16398.344066237336;16745;8103.756887293391;8346.499210047356;8390.916090839075;8411.705590304371;8413.411061225717;5232.92150617741;5259.342582177228;5406.83779486616;5440.945590544123;5483.972580137113;2146.6350720377573;2155.902984365707;2163.242864997254;2174.923877664274;2198.1537108213292;28115.29711757202;28440.857795711097;29356.853215733998;29494.70505294941;29624.555631665542;7039.507234493536;7054.541454805421;7071.752488662872;7212.531185472927;7315.122185337794;18717.438476845717;18728.812711872706;18741.250349985847;18743.90628046851;18751.812481875008;8373.288270496954;8472.618732157067;8522.914770852336;8538.231559159705;8538.658453661908;7732.149463559004;7816.452848300678;7956.681732730626;7969.123396426388;7990.68037278514;5518.448155184449;5648.152777083388;5685.289338832646;5716.514096301861;5805.216295800013;1612.6774645071032;1618.8543031127256;1630.290823491785;1633.085472135608;1636.4108920788506;918.6968300460832;931.753085432361;934.4533447933036;945.3477100800426;950.0119875329718;7918;8040.195980401913;8076.959615202004;8105.513669179868;8126.431149819574;779.4543819326381;781.6248200863618;784.0395515493475;784.5057613703444;784.9136594974548;4845.554179166115;5468.507834294933;5472.097103977799;5488.807891723771;5678.943210567924;23180.420489487897;23317.650235246485;24188;24299;24362;23099.19152829643;23249.97750292485;23268.185613503447;23314.883425583103;23388.064477420714;12108.757824843453;12700.174488658393;12788.296643684687;12809.142674312172;12817.359132043546;9448;9571.090746379083;10014.649487268201;10076.445795481319;10388;20596;20676.896615516827;20713.89643051775;20904.790952090894;21019.474513137295;21184.894075529523;21372.145114195257;21426.57146857085;21564.568708626055;21755.456113597287;13180.143290686075;13501.662458438617;13516.391762370913;13520.526781562598;13522.459101635824;15054.473093441677;15295.541133766004;15421.074735515906;15460.768088478906;15501.612459688147;11573.537058517903;11623.825642615198;11730.178887477794;11976.880231197576;12006.63980080599;11480.311181329147;11501.942490287494;11929.522819087486;11972.281663100242;12035.699107522381;9989.051040151175;10124.088832005154;10144.847827282745;10173.694789156338;10195.898041019494;7589.279018493235;7700.114486834086;7700.884486732815;7711.807204819925;7727.072751269883;7981.281684648409;8013;8027.879581806161;8096.7166149184495;8136.308413784895 12:12:52 INFO - PID 3739 | |4;prototype.html;551.5390921964673;556.749462741766;572.6792995922284;574.2649408756791;576.2422414524899;6777.62723050232;6810.38706516414;6821.215560210578;6846.281140480252;6863.828404289895;6729.25978142404;6773.695183716733;6781.830454238647;6784.728610855572;6818.386345228929;5493.587980901439;5493.7802487900435;5519.089350257203;5543.1408131739645;5556.666400063976;5159.355080614923;5307.628466007381;5482;5503.20203570482;5574;5283.339582552181;5307.124324486455;5308.734563271821;5314.468553144693;5322.946770532303;5218.66078704883;5246.527812496894;5269.6311258211945;5284.9735751320995;5295.947040529584;4667.113248482778;4688.203005489075;4780.450248221464;4782.426108866958;4806.4712881583;6685.799426017227;6697.129373181455;6716.2276338221245;6719.664016799141;6792.660366981631;6262.373762623702;6263.686815659199;6271.404216599416;6274.843128921768;6277.686115694197;7221.747238846668;7222.133343998701;7233.89149162763;7238.782836514913;7249.855002899965;5394.460553944614;5403.972980135113;5407.783688652449;5412.91880621791;5421.756020979065;7265.527740696838;7349.522281051714;7455.912344543536;7621.923780762177;7635.694572217105;5520.861978450571;5526.253955716007;5533.972330138324;5544.889102217933;5548.833534993957;5598.720063996784;5603.719814009283;5618.381977982419;5632.436756324417;5650.830475085754;6279.591826531261;6291.081220227783;6300.117983482316;6308.810735677937;6313.2424109107205;7276.563406195645;7291.453141014339;7302.233265507151;7307.671154798047;7323.963380183065;1951.4926119209108;1955.0420294056094;1964.8603809790288;1969.8916559589074;1976.3478052242726;1285.3701686173708;1338.712176881975;1345.8115863779078;1351.776642138866;1358.4566173530666;6257.78097766576;6260.248770147611;6266.436020758154;6303.684815759194;6356.745730170742;7282.453815963718;7288.817779555554;7299.781006569811;7307;7310.671019804122;6201.503879689614;6208.937910620926;6240.719167637467;6246.3753624637175;6255.968720156461;3876.8061596920043;3902.9804850975565;3909.6481316681065;3910.8631197907935;3935.763854168759;7125.251848555827;7142.21435642079;7158.892616610755;7165.498415110892;7182.42540596751;7570.735024274124;7582.431317651198;7583.848323033509;7593.5823529706395;7601.733939312208;7168.247334029956;7174.390176835028;7176.892346614805;7185.461090418135;7197.856042879113;7365.742199023009;7371.226021267689;7385.630718464163;7394.482386232912;7402.851942961218;7405.740799072007;7435.591042492499;7447.851042979219;7465.776026719207;7476.803711406149;6947.861042779015;6968.407685346802;6994;7091.2554181810165;7168.534045287248;5445.591580631548;5459.890802184013;5518.586106042143;5536.695481748381;5554.50009499147;7010.789676309719;7141.85716285661;7225.277472252733;7345.265473452612;7357.668904899186;7002.474814389043;7056.329648683367;7088.681009354488;7159.284071592799;7201;6942.2363540011565;6964.965175174092;7003;7142;7158.141023077264;6819.863602728017;6977.651117444006;7030.894536582057;7052.836282013411;7144.178419481618;7114.857702846018;7134;7141.392981596622;7145.892811607934;7156.463265255231;6904.206016308189;6904.927307348182;6905.689243983932;6963.582185068912;6970.546914450544 12:12:52 INFO - PID 3739 | |5;yui.html;975.5658731864319;979.3732011512631;1010.3180353261547;1011.0395124631602;1017.1049476460715;19963;20404.183832646693;20875.791242087573;20878.791212087912;21329.466763330925;19102.52243693908;19276.518087047833;19279.13243904024;19328.3235086772;19333.130009149587;1038.5118994072811;1063.0910571461404;1070.0904231403322;1074.4520294649706;1074.9516271767789;1085.5766251162054;1089.215764649451;1089.2974031749516;1090.7982023325671;1091.5142761471147;1068.4604274841195;1113.8050841102815;1116.5589592111112;1116.9944150279237;1118.0999295567065;1102.1568500097433;1107.9390633515154;1109.3343993603773;1110.0176343935595;1111.83322501625;818.0142927772021;818.66434761748;819.4591569564119;820.6061090676449;821.3880658909092;18191.818081819143;18264.45206643803;18295.359662411753;18478.53803654906;18880.811191888042;21023.78976210249;21083.68374474369;21617.783822161735;21639;21778.128874844988;18266.81265717724;18373.448796536126;18480.90759546207;18618.81381186198;18693.906530467397;3908.472356231901;3952.3676211805973;3952.9209415811524;3954.2486927483687;3954.426608141803;133571.33214333866;134015;134797;135016;137220;16899.23953422099;16948;17427.651446971242;17464.47606571805;17668.91165544164;33043.173920652174;33471;33601.66398336034;33746.32507349888;33895.661043389744;8489.915100849035;8503.319734421231;8505.064442911274;8596.527191004554;8605.225529702231;4421.756803375845;4493.393391892053;4498;4498.820047198075;4500.482444518857;5674.744636491368;5793.246877905845;5811.3026436827;5844.970775146097;5893.793717219876;1082.2856914436434;1103.5475455063386;1124.5614210457927;1126.7014241226082;1127.7406196574834;735.7608777147449;752.0862152484757;755.1504557372955;771.4407054885163;772.8724760414468;11868.347240901834;11947.880521194851;12154.696132596755;12755.48978040868;12757.68105797344;738.4978214813979;739.0835364148525;744.556988591787;752.9924700752922;761.5012167030604;5616.634918730269;5641.153826925994;5645.774169033275;6477.222733272038;6594;136214;137212;140833.5916640841;142226.28886855498;147163;114812;116804;120355.39822300832;121059.39470302592;122908.38545807214;95450.52274738582;97484.51257743666;103341.48329258306;104274;108837.99999999841;13306.467741290628;13319.733605328032;13608.591742247749;13970.79043814364;14216.360263787947;39117.2176556473;48609.270860936405;49843.50156498388;50198;51038;65539.751459799;78137.218627813;80267.19732802831;81143;87003.56498217468;20094;20149.39551813448;20357.898210508854;20407.285744998855;20747.688784668542;21411.036503357074;23290.76709232886;23357.065717371115;23658.645120322864;23997.400064997815;21130.154793808077;21354.252601158885;21656.242031528822;21984;22009.33971980843;18040.188191531674;18372.908135459238;18538.629227415648;18647.347342842935;19326.903365483082;18809.717854232473;18816.341428049953;20822.27122050721;21293;21537.353879383645;13953.441862325799;14121.576352709435;14206.644833879236;14503.202323871867;14557.708845822812;14830.184339861411;15326.540203793904;15378.538643840702;15406.229688515305;15498.302576383865 12:12:52 INFO - PID 3739 | __end_tp_report 12:12:52 INFO - PID 3739 | __start_cc_report 12:12:52 INFO - PID 3739 | _x_x_mozilla_cycle_collect,353 12:12:52 INFO - PID 3739 | __end_cc_report 12:12:52 INFO - PID 3739 | __startTimestamp1504465972272__endTimestamp 12:12:52 INFO - PID 3739 | 12:12:52 INFO - PID 3739 | ------- Summary: start ------- 12:12:52 INFO - PID 3739 | Number of tests: 6 12:12:52 INFO - PID 3739 | 12:12:52 INFO - PID 3739 | [#0] dojo.html Cycles:165 Average:6858.30 Median:7167.21 stddev:762.72 (10.6%) stddev-sans-first:729.05 12:12:52 INFO - PID 3739 | Values: 3905.9 3925.1 3964.0 3969.0 3979.5 6759.0 6857.9 6878.9 6911.2 6940.0 6768.2 6841.5 6841.9 6845.6 6884.2 6110.8 6119.0 6152.7 6165.9 6173.2 6128.9 6162.2 6187.1 6189.1 6196.8 6225.9 6235.2 6253.9 6271.2 6300.6 6095.9 6137.2 6162.7 6189.5 6264.9 5757.4 5970.6 6071.6 6082.7 6109.5 6873.3 6882.0 6928.4 7010.8 7026.9 7482.4 7503.4 7581.6 7593.6 7610.1 7441.3 7581.0 7585.2 7585.4 7685.0 6983.1 6988.5 6994.4 6996.2 7003.4 7516.9 7622.3 7734.7 7737.8 7747.1 7336.4 7337.6 7424.1 7425.1 7449.2 7319.2 7341.1 7411.5 7426.0 7473.5 7356.6 7459.9 7510.5 7573.7 7577.3 7458.7 7491.4 7491.5 7577.8 7614.1 6146.9 6249.3 6273.7 6292.4 6313.4 5505.1 5514.3 5555.2 5575.6 5577.7 6308.4 6329.3 6389.7 6402.9 6473.3 7341.4 7358.9 7362.6 7363.3 7373.3 6346.9 6354.1 6360.2 6360.5 6364.7 6609.2 6624.3 6670.8 6757.8 6759.8 7303.7 7308.0 7308.7 7324.8 7325.0 7309.3 7423.1 7427.8 7439.7 7542.2 7167.2 7199.0 7211.1 7211.3 7220.5 6945.1 7377.9 7404.6 7501.2 7521.4 7489.0 7503.4 7520.5 7580.0 7601.7 7101.4 7186.3 7187.2 7204.2 7300.3 7186.7 7249.0 7255.1 7278.5 7281.5 7190.6 7264.4 7279.0 7279.2 7303.3 7177.4 7178.3 7237.9 7255.2 7335.9 6896.2 6993.1 7001.2 7054.3 7126.3 12:12:52 INFO - PID 3739 | 12:12:52 INFO - PID 3739 | [#1] ext.html Cycles:180 Average:40747.96 Median:37074.54 stddev:59324.15 (160.0%) stddev-sans-first:59415.19 12:12:52 INFO - PID 3739 | Values: 918.6 926.8 944.8 978.9 987.8 42601.0 43902.3 44327.0 44437.8 44448.8 40532.6 40734.8 41070.2 41076.4 41201.6 7427.6 7679.1 7687.5 7723.0 7752.5 7534.2 7537.8 7549.4 7558.9 7561.1 7531.2 7562.5 7576.8 7591.0 7595.3 7429.5 7540.8 7546.8 7549.0 7566.7 6933.9 7039.9 7062.7 7063.5 7065.2 36624.6 36972.8 37103.3 37191.3 37241.1 3586.3 3606.9 3609.7 3611.2 3613.8 29311.3 29496.9 29563.7 29570.6 29694.1 4855.0 4988.6 5012.4 5032.8 5040.2 201482.0 203407.0 206446.0 207382.0 214014.0 51381.5 51558.2 51765.5 51912.7 52016.5 53216.7 53942.5 53959.2 54013.2 54224.0 14015.5 14177.1 14237.2 14244.4 14322.3 11202.9 11205.9 11216.8 11226.8 11322.5 13601.7 13671.5 13682.2 13706.5 13876.5 5255.0 5294.5 5332.6 5340.7 5386.8 1077.6 1089.9 1116.6 1130.5 1131.1 25473.7 25512.7 25812.1 25812.5 25965.5 1184.1 1189.5 1202.1 1220.3 1226.1 13391.9 13408.4 13463.3 13568.8 13588.0 246943.0 247042.0 247843.0 247937.0 248049.0 57910.4 58074.1 58221.7 58279.7 58347.4 1491.8 1512.8 1513.2 1523.0 1543.3 25019.0 25147.6 25165.0 25199.1 25340.2 41344.2 41423.8 41523.6 41549.6 41677.0 227161.0 228965.0 229060.0 229261.0 229893.0 36790.8 38592.4 38788.5 39127.0 39156.4 48789.3 50041.0 50075.2 50408.0 50498.0 36796.1 36994.6 37045.8 37219.6 37340.0 36039.6 36441.8 36676.6 36799.0 36896.0 42579.6 43230.0 43435.6 43438.0 43859.8 35036.6 35088.8 35161.6 35348.5 35552.5 26547.9 26728.7 27095.6 27439.2 27527.3 12:12:52 INFO - PID 3739 | 12:12:52 INFO - PID 3739 | [#2] jquery.html Cycles:180 Average:42751.30 Median:13158.49 stddev:113706.10 (864.1%) stddev-sans-first:113981.00 12:12:52 INFO - PID 3739 | Values: 586.0 603.4 605.9 610.9 617.6 23039.0 23047.2 23956.4 23978.6 23996.0 26693.3 27107.9 27197.9 27253.0 27283.0 6749.7 6896.9 6902.2 6911.4 6923.2 6697.5 6778.0 6840.3 6936.1 6995.9 6942.2 6948.5 6949.8 6951.1 6952.1 6897.7 6940.8 6942.6 6942.7 6942.7 6455.3 6465.4 6466.3 6468.9 6474.1 30158.2 30173.1 30862.2 31053.5 31308.2 2271.9 2315.3 2323.3 2323.4 2324.3 20319.7 20558.9 20576.8 20606.6 20626.4 2393.1 2510.9 2519.9 2528.0 2538.8 150275.0 154140.0 154495.0 154514.6 154732.2 15564.1 16338.3 16461.9 16465.4 16762.9 25595.1 25655.2 25660.4 25696.0 25761.7 10590.6 10592.9 10598.6 10786.0 10826.6 8629.4 9797.2 9803.4 9853.8 10002.0 8693.7 9800.6 9828.2 9966.7 10121.7 2916.7 2973.4 2974.7 2984.7 3008.9 1054.0 1057.1 1078.4 1081.7 1086.2 21702.1 21760.3 21896.2 21919.2 22087.4 1120.9 1121.7 1126.1 1127.4 1133.7 11980.3 12239.4 12307.0 12346.8 12363.6 664706.0 683070.0 685016.0 687051.0 692375.0 136226.0 138415.0 138758.0 139153.3 140231.0 1254.2 1283.4 1293.2 1315.2 1319.2 23238.6 23923.9 24047.4 24180.0 24190.5 66914.0 66923.7 67459.7 69083.3 70246.9 84904.6 86410.6 86763.0 87435.6 87471.6 13312.5 13773.1 15392.1 15522.3 16002.2 24232.6 24405.3 24464.4 24625.3 24659.6 19566.5 20028.7 20287.8 20347.4 20378.8 16580.0 16628.3 16878.6 16886.4 16929.2 20152.8 20224.2 20548.3 20630.2 20647.9 15488.4 15625.7 15658.3 15665.6 15712.8 17676.7 19689.6 20069.3 20075.1 20111.2 12:12:52 INFO - PID 3739 | 12:12:52 INFO - PID 3739 | [#3] mootools.html Cycles:180 Average:10134.91 Median:2677.75 stddev:7404.95 (276.5%) stddev-sans-first:7396.54 12:12:52 INFO - PID 3739 | Values: 1385.7 1458.8 1460.1 1473.0 1483.6 16182.2 16742.5 16779.9 16796.4 16884.7 15841.4 16344.2 17097.7 17106.5 17120.7 2853.0 2907.9 2912.1 2922.5 2923.8 2756.3 2795.1 2832.2 2846.4 2846.6 2763.1 2779.0 2796.1 2801.1 2801.7 2700.9 2722.6 2727.3 2728.4 2728.8 2652.2 2657.8 2668.9 2669.1 2686.4 16194.7 16245.1 16373.6 16398.3 16745.0 8103.8 8346.5 8390.9 8411.7 8413.4 5232.9 5259.3 5406.8 5440.9 5484.0 2146.6 2155.9 2163.2 2174.9 2198.2 28115.3 28440.9 29356.9 29494.7 29624.6 7039.5 7054.5 7071.8 7212.5 7315.1 18717.4 18728.8 18741.3 18743.9 18751.8 8373.3 8472.6 8522.9 8538.2 8538.7 7732.1 7816.5 7956.7 7969.1 7990.7 5518.4 5648.2 5685.3 5716.5 5805.2 1612.7 1618.9 1630.3 1633.1 1636.4 918.7 931.8 934.5 945.3 950.0 7918.0 8040.2 8077.0 8105.5 8126.4 779.5 781.6 784.0 784.5 784.9 4845.6 5468.5 5472.1 5488.8 5678.9 23180.4 23317.7 24188.0 24299.0 24362.0 23099.2 23250.0 23268.2 23314.9 23388.1 12108.8 12700.2 12788.3 12809.1 12817.4 9448.0 9571.1 10014.6 10076.4 10388.0 20596.0 20676.9 20713.9 20904.8 21019.5 21184.9 21372.1 21426.6 21564.6 21755.5 13180.1 13501.7 13516.4 13520.5 13522.5 15054.5 15295.5 15421.1 15460.8 15501.6 11573.5 11623.8 11730.2 11976.9 12006.6 11480.3 11501.9 11929.5 11972.3 12035.7 9989.1 10124.1 10144.8 10173.7 10195.9 7589.3 7700.1 7700.9 7711.8 7727.1 7981.3 8013.0 8027.9 8096.7 8136.3 12:12:52 INFO - PID 3739 | 12:12:52 INFO - PID 3739 | [#4] prototype.html Cycles:180 Average:6028.07 Median:6775.66 stddev:1692.81 (25.0%) stddev-sans-first:1646.89 12:12:52 INFO - PID 3739 | Values: 551.5 556.7 572.7 574.3 576.2 6777.6 6810.4 6821.2 6846.3 6863.8 6729.3 6773.7 6781.8 6784.7 6818.4 5493.6 5493.8 5519.1 5543.1 5556.7 5159.4 5307.6 5482.0 5503.2 5574.0 5283.3 5307.1 5308.7 5314.5 5322.9 5218.7 5246.5 5269.6 5285.0 5295.9 4667.1 4688.2 4780.5 4782.4 4806.5 6685.8 6697.1 6716.2 6719.7 6792.7 6262.4 6263.7 6271.4 6274.8 6277.7 7221.7 7222.1 7233.9 7238.8 7249.9 5394.5 5404.0 5407.8 5412.9 5421.8 7265.5 7349.5 7455.9 7621.9 7635.7 5520.9 5526.3 5534.0 5544.9 5548.8 5598.7 5603.7 5618.4 5632.4 5650.8 6279.6 6291.1 6300.1 6308.8 6313.2 7276.6 7291.5 7302.2 7307.7 7324.0 1951.5 1955.0 1964.9 1969.9 1976.3 1285.4 1338.7 1345.8 1351.8 1358.5 6257.8 6260.2 6266.4 6303.7 6356.7 7282.5 7288.8 7299.8 7307.0 7310.7 6201.5 6208.9 6240.7 6246.4 6256.0 3876.8 3903.0 3909.6 3910.9 3935.8 7125.3 7142.2 7158.9 7165.5 7182.4 7570.7 7582.4 7583.8 7593.6 7601.7 7168.2 7174.4 7176.9 7185.5 7197.9 7365.7 7371.2 7385.6 7394.5 7402.9 7405.7 7435.6 7447.9 7465.8 7476.8 6947.9 6968.4 6994.0 7091.3 7168.5 5445.6 5459.9 5518.6 5536.7 5554.5 7010.8 7141.9 7225.3 7345.3 7357.7 7002.5 7056.3 7088.7 7159.3 7201.0 6942.2 6965.0 7003.0 7142.0 7158.1 6819.9 6977.7 7030.9 7052.8 7144.2 7114.9 7134.0 7141.4 7145.9 7156.5 6904.2 6904.9 6905.7 6963.6 6970.5 12:12:52 INFO - PID 3739 | 12:12:52 INFO - PID 3739 | [#5] yui.html Cycles:180 Average:26919.25 Median:19327.61 stddev:37950.06 (196.4%) stddev-sans-first:38006.52 12:12:52 INFO - PID 3739 | Values: 975.6 979.4 1010.3 1011.0 1017.1 19963.0 20404.2 20875.8 20878.8 21329.5 19102.5 19276.5 19279.1 19328.3 19333.1 1038.5 1063.1 1070.1 1074.5 1075.0 1085.6 1089.2 1089.3 1090.8 1091.5 1068.5 1113.8 1116.6 1117.0 1118.1 1102.2 1107.9 1109.3 1110.0 1111.8 818.0 818.7 819.5 820.6 821.4 18191.8 18264.5 18295.4 18478.5 18880.8 21023.8 21083.7 21617.8 21639.0 21778.1 18266.8 18373.4 18480.9 18618.8 18693.9 3908.5 3952.4 3952.9 3954.2 3954.4 133571.3 134015.0 134797.0 135016.0 137220.0 16899.2 16948.0 17427.7 17464.5 17668.9 33043.2 33471.0 33601.7 33746.3 33895.7 8489.9 8503.3 8505.1 8596.5 8605.2 4421.8 4493.4 4498.0 4498.8 4500.5 5674.7 5793.2 5811.3 5845.0 5893.8 1082.3 1103.5 1124.6 1126.7 1127.7 735.8 752.1 755.2 771.4 772.9 11868.3 11947.9 12154.7 12755.5 12757.7 738.5 739.1 744.6 753.0 761.5 5616.6 5641.2 5645.8 6477.2 6594.0 136214.0 137212.0 140833.6 142226.3 147163.0 114812.0 116804.0 120355.4 121059.4 122908.4 95450.5 97484.5 103341.5 104274.0 108838.0 13306.5 13319.7 13608.6 13970.8 14216.4 39117.2 48609.3 49843.5 50198.0 51038.0 65539.8 78137.2 80267.2 81143.0 87003.6 20094.0 20149.4 20357.9 20407.3 20747.7 21411.0 23290.8 23357.1 23658.6 23997.4 21130.2 21354.3 21656.2 21984.0 22009.3 18040.2 18372.9 18538.6 18647.3 19326.9 18809.7 18816.3 20822.3 21293.0 21537.4 13953.4 14121.6 14206.6 14503.2 14557.7 14830.2 15326.5 15378.5 15406.2 15498.3 12:12:52 INFO - PID 3739 | -------- Summary: end -------- 12:12:52 INFO - PID 3739 | 12:12:52 INFO - TEST-INFO | 3739: exit 0 12:12:52 INFO - TEST-OK | dromaeo_css | took 1111668ms 12:12:52 INFO - TEST-START | kraken 12:12:52 INFO - Initialising browser for kraken test... 12:12:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38996/getInfo.html -profile /tmp/tmp1Gsnj6/profile 12:12:52 INFO - TEST-INFO | started process 4109 (/builds/slave/test/build/application/firefox/firefox http://localhost:38996/getInfo.html) 12:12:58 INFO - TEST-INFO | 4109: exit 0 12:12:58 INFO - Browser initialized. 12:12:58 INFO - Running cycle 1/1 for kraken test... 12:12:58 INFO - Using env: {'DISPLAY': ':0', 12:12:58 INFO - 'HOME': '/home/cltbld', 12:12:58 INFO - 'JSGC_DISABLE_POISONING': '1', 12:12:58 INFO - 'LANG': 'en_US.UTF-8', 12:12:58 INFO - 'LANGUAGE': 'en_US:en', 12:12:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:12:58 INFO - 'LOGNAME': 'cltbld', 12:12:58 INFO - 'MAIL': '/var/mail/cltbld', 12:12:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:12:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:12:58 INFO - 'MOZ_CRASHREPORTER': '1', 12:12:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:12:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:12:58 INFO - 'MOZ_NO_REMOTE': '1', 12:12:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:12:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:12:58 INFO - 'NO_EM_RESTART': '1', 12:12:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:12:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:12:58 INFO - 'PWD': '/builds/slave/test', 12:12:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:12:58 INFO - 'RUST_BACKTRACE': 'full', 12:12:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:12:58 INFO - 'SHELL': '/bin/bash', 12:12:58 INFO - 'SHLVL': '1', 12:12:58 INFO - 'TERM': 'linux', 12:12:58 INFO - 'TMOUT': '86400', 12:12:58 INFO - 'USER': 'cltbld', 12:12:58 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605', 12:12:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:12:58 INFO - '_': '/tools/buildbot/bin/python'} 12:12:58 INFO - TEST-INFO | started process 4300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1Gsnj6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:12:59 INFO - PID 4300 | 12:12:59 INFO - PID 4300 | (/builds/slave/test/build/application/firefox/firefox:4354): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:12:59 INFO - PID 4300 | 12:13:09 INFO - PID 4300 | RSS: Main: 163389440 12:13:09 INFO - PID 4300 | 12:13:11 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38996/tests/kraken/driver.html?testName=audio-beat-detection) 12:13:12 INFO - PID 4300 | RSS: Main: 167903232 12:13:12 INFO - PID 4300 | 12:13:15 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38996/tests/kraken/driver.html?testName=audio-dft) 12:13:15 INFO - PID 4300 | RSS: Main: 172122112 12:13:15 INFO - PID 4300 | 12:13:18 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38996/tests/kraken/driver.html?testName=audio-fft) 12:13:19 INFO - PID 4300 | RSS: Main: 164683776 12:13:19 INFO - PID 4300 | 12:13:21 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38996/tests/kraken/driver.html?testName=audio-oscillator) 12:13:22 INFO - PID 4300 | RSS: Main: 166117376 12:13:22 INFO - PID 4300 | 12:13:24 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38996/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:13:24 INFO - PID 4300 | RSS: Main: 165769216 12:13:24 INFO - PID 4300 | 12:13:27 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38996/tests/kraken/driver.html?testName=imaging-darkroom) 12:13:28 INFO - PID 4300 | RSS: Main: 165945344 12:13:28 INFO - PID 4300 | 12:13:31 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38996/tests/kraken/driver.html?testName=imaging-desaturate) 12:13:32 INFO - PID 4300 | RSS: Main: 167624704 12:13:32 INFO - PID 4300 | 12:13:35 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38996/tests/kraken/driver.html?testName=json-parse-financial) 12:13:35 INFO - PID 4300 | RSS: Main: 167612416 12:13:35 INFO - PID 4300 | 12:13:37 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38996/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:13:38 INFO - PID 4300 | RSS: Main: 167190528 12:13:38 INFO - PID 4300 | 12:13:39 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38996/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:13:40 INFO - PID 4300 | RSS: Main: 167215104 12:13:40 INFO - PID 4300 | 12:13:42 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38996/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:13:42 INFO - PID 4300 | RSS: Main: 164233216 12:13:42 INFO - PID 4300 | 12:13:45 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:13:46 INFO - PID 4300 | RSS: Main: 163991552 12:13:46 INFO - PID 4300 | 12:13:48 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:13:49 INFO - PID 4300 | RSS: Main: 164593664 12:13:49 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | Cycle 1(1): loaded http://localhost:38996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38996/tests/kraken/driver.html?testName=ai-astar) 12:13:51 INFO - PID 4300 | RSS: Main: 166207488 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | __start_tp_report 12:13:51 INFO - PID 4300 | _x_x_mozilla_page_load 12:13:51 INFO - PID 4300 | _x_x_mozilla_page_load_details 12:13:51 INFO - PID 4300 | |i|pagename|runs| 12:13:51 INFO - PID 4300 | |0;ai-astar;96;111;99;106;105;106;108;98;107;106 12:13:51 INFO - PID 4300 | |1;audio-beat-detection;142;112;139;175;135;136;139;112;169;112 12:13:51 INFO - PID 4300 | |2;audio-dft;162;161;162;160;161;161;160;161;160;163 12:13:51 INFO - PID 4300 | |3;audio-fft;73;74;72;172;74;73;73;73;165;73 12:13:51 INFO - PID 4300 | |4;audio-oscillator;79;78;135;77;78;78;125;81;78;128 12:13:51 INFO - PID 4300 | |5;imaging-gaussian-blur;114;114;115;115;114;114;118;117;117;114 12:13:51 INFO - PID 4300 | |6;imaging-darkroom;149;149;149;149;149;151;150;149;148;149 12:13:51 INFO - PID 4300 | |7;imaging-desaturate;94;94;94;94;94;94;93;94;98;94 12:13:51 INFO - PID 4300 | |8;json-parse-financial;46;44;47;45;43;43;45;54;43;44 12:13:51 INFO - PID 4300 | |9;json-stringify-tinderbox;35;37;36;36;37;37;37;38;36;36 12:13:51 INFO - PID 4300 | |10;stanford-crypto-aes;75;73;71;72;72;72;74;74;74;73 12:13:51 INFO - PID 4300 | |11;stanford-crypto-ccm;111;110;109;109;113;113;129;122;107;145 12:13:51 INFO - PID 4300 | |12;stanford-crypto-pbkdf2;157;157;171;157;157;160;155;156;155;158 12:13:51 INFO - PID 4300 | |13;stanford-crypto-sha256-iterative;61;59;57;59;58;60;62;59;59;58 12:13:51 INFO - PID 4300 | __end_tp_report 12:13:51 INFO - PID 4300 | __start_cc_report 12:13:51 INFO - PID 4300 | _x_x_mozilla_cycle_collect,890 12:13:51 INFO - PID 4300 | __end_cc_report 12:13:51 INFO - PID 4300 | __startTimestamp1504466031299__endTimestamp 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | ------- Summary: start ------- 12:13:51 INFO - PID 4300 | Number of tests: 14 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#0] ai-astar Cycles:10 Average:104.20 Median:109.50 stddev:4.85 (4.4%) stddev-sans-first:4.14 12:13:51 INFO - PID 4300 | Values: 96.0 111.0 99.0 106.0 105.0 106.0 108.0 98.0 107.0 106.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:139.00 stddev:22.06 (15.9%) stddev-sans-first:23.33 12:13:51 INFO - PID 4300 | Values: 142.0 112.0 139.0 175.0 135.0 136.0 139.0 112.0 169.0 112.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 12:13:51 INFO - PID 4300 | Values: 162.0 161.0 162.0 160.0 161.0 161.0 160.0 161.0 160.0 163.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#3] audio-fft Cycles:10 Average:92.20 Median:73.00 stddev:40.25 (55.1%) stddev-sans-first:42.09 12:13:51 INFO - PID 4300 | Values: 73.0 74.0 72.0 172.0 74.0 73.0 73.0 73.0 165.0 73.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:78.00 stddev:24.73 (31.7%) stddev-sans-first:25.65 12:13:51 INFO - PID 4300 | Values: 79.0 78.0 135.0 77.0 78.0 78.0 125.0 81.0 78.0 128.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:115.00 stddev:1.55 (1.3%) stddev-sans-first:1.58 12:13:51 INFO - PID 4300 | Values: 114.0 114.0 115.0 115.0 114.0 114.0 118.0 117.0 117.0 114.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 12:13:51 INFO - PID 4300 | Values: 149.0 149.0 149.0 149.0 149.0 151.0 150.0 149.0 148.0 149.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:1.34 (1.4%) stddev-sans-first:1.41 12:13:51 INFO - PID 4300 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 98.0 94.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#8] json-parse-financial Cycles:10 Average:45.40 Median:45.00 stddev:3.31 (7.3%) stddev-sans-first:3.50 12:13:51 INFO - PID 4300 | Values: 46.0 44.0 47.0 45.0 43.0 43.0 45.0 54.0 43.0 44.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#9] json-stringify-tinderbox Cycles:10 Average:36.50 Median:37.00 stddev:0.85 (2.3%) stddev-sans-first:0.71 12:13:51 INFO - PID 4300 | Values: 35.0 37.0 36.0 36.0 37.0 37.0 37.0 38.0 36.0 36.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:73.50 stddev:1.25 (1.7%) stddev-sans-first:1.09 12:13:51 INFO - PID 4300 | Values: 75.0 73.0 71.0 72.0 72.0 72.0 74.0 74.0 74.0 73.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#11] stanford-crypto-ccm Cycles:10 Average:116.80 Median:113.00 stddev:12.01 (10.6%) stddev-sans-first:12.55 12:13:51 INFO - PID 4300 | Values: 111.0 110.0 109.0 109.0 113.0 113.0 129.0 122.0 107.0 145.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.30 Median:157.00 stddev:4.69 (3.0%) stddev-sans-first:4.95 12:13:51 INFO - PID 4300 | Values: 157.0 157.0 171.0 157.0 157.0 160.0 155.0 156.0 155.0 158.0 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.20 Median:59.00 stddev:1.48 (2.5%) stddev-sans-first:1.41 12:13:51 INFO - PID 4300 | Values: 61.0 59.0 57.0 59.0 58.0 60.0 62.0 59.0 59.0 58.0 12:13:51 INFO - PID 4300 | -------- Summary: end -------- 12:13:51 INFO - PID 4300 | 12:13:51 INFO - PID 4300 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:13:51 INFO - TEST-INFO | 4300: exit 0 12:13:52 INFO - TEST-OK | kraken | took 59034ms 12:13:52 INFO - SUITE-END | took 1170s 12:13:52 INFO - Completed test suite (00:19:31) 12:13:52 INFO - javascript benchmark 12:13:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9246.64018830898, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3905.9414108788355, 3925.0579860833373, 3963.980180099099, 3969.0, 3979.4627725257083, 6759.0, 6857.897131543024, 6878.9312106878915, 6911.2397636259975, 6940.0, 6768.153980752406, 6841.486888483359, 6841.897371539431, 6845.555038922479, 6884.173899132111, 6110.8472288192925, 6118.999999999989, 6152.692365381713, 6165.876682466348, 6173.228346456693, 6128.877422451548, 6162.198914141178, 6187.133801267826, 6189.133521307021, 6196.78311259106, 6225.937740622581, 6235.158253635746, 6253.937460625404, 6271.184745983039, 6300.5589608727405, 6095.908561371583, 6137.202163718733, 6162.7226774795245, 6189.50483961282, 6264.937350626504, 5757.366689664133, 5970.611910225864, 6071.574989750719, 6082.726277317487, 6109.541784366146, 6873.347032031999, 6882.03651488792, 6928.376446119873, 7010.75462358815, 7026.894596581055, 7482.401407887356, 7503.362214211798, 7581.620918954085, 7593.6203189840835, 7610.124835643916, 7441.293077157661, 7581.0, 7585.203553626845, 7585.431092668072, 7685.0, 6983.127109111361, 6988.545744526589, 6994.405475534535, 6996.195437524699, 7003.439724822053, 7516.887246691304, 7622.31399174077, 7734.651940662684, 7737.8452430951065, 7747.070351557736, 7336.413086953031, 7337.633118344062, 7424.109106907098, 7425.071866016748, 7449.180590135081, 7319.160794617658, 7341.119065712149, 7411.481196316314, 7425.962870185723, 7473.51422157558, 7356.558606483628, 7459.8508029839095, 7510.511816731895, 7573.659185336673, 7577.280158384945, 7458.70165193397, 7491.400687944952, 7491.475596708287, 7577.848443031108, 7614.124375696755, 6146.938530614726, 6249.312575616678, 6273.7176827042895, 6292.370762923671, 6313.3686631336495, 5505.119180931032, 5514.255575497336, 5555.2222688823595, 5575.553955683536, 5577.693226872561, 6308.369163083655, 6329.3354197809485, 6389.6805159741825, 6402.8719425611225, 6473.255575608865, 7341.449391295568, 7358.852822943618, 7362.558246505227, 7363.300486453778, 7373.29953654402, 6346.904796428057, 6354.078658594571, 6360.173177486897, 6360.491160707133, 6364.745410183634, 6609.239937407164, 6624.304448032887, 6670.7665231716655, 6757.763478278237, 6759.831004224934, 7303.671334789948, 7308.013418188477, 7308.744193953186, 7324.780256592311, 7325.0, 7309.305615966475, 7423.146338171179, 7427.7771666850085, 7439.665215065227, 7542.245775422633, 7167.211606723361, 7199.0, 7211.062561866924, 7211.314925082109, 7220.458465614995, 6945.131858517685, 7377.926220737724, 7404.592747398945, 7501.249875012455, 7521.360684341892, 7488.9625551871895, 7503.362214211853, 7520.473566850268, 7579.962100189464, 7601.695932162652, 7101.360877521048, 7186.281371862772, 7187.245339239514, 7204.207537171012, 7300.342969132591, 7186.7125314986815, 7248.96375518119, 7255.09311336083, 7278.526895751759, 7281.526700764433, 7190.640467976685, 7264.418846492374, 7279.0, 7279.235680253497, 7303.342699157101, 7177.354038136384, 7178.28217178278, 7237.855242895218, 7255.2019277880445, 7335.853282934205, 6896.241413444617, 6993.0908981832035, 7001.229864714775, 7054.329838665319, 7126.251743567058], "value": 6808.331839752315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [918.6095909238574, 926.7868390270241, 944.7826999790049, 978.9216862650989, 987.8320685483467, 42601.0, 43902.34146487805, 44327.0, 44437.77781111098, 44448.77775611117, 40532.59467405325, 40734.79632601833, 41070.17859642806, 41076.38385424221, 41201.58798412015, 7427.554346739186, 7679.116901556309, 7687.500312479699, 7722.996010518625, 7752.5348479091435, 7534.171241163468, 7537.773866784005, 7549.35830454412, 7558.886616700754, 7561.092668879742, 7531.209223031585, 7562.508436951608, 7576.810579735496, 7591.0, 7595.278448547379, 7429.517081389719, 7540.8114797129965, 7546.773596792077, 7549.0, 7566.735164269252, 6933.895991560131, 7039.8592028159665, 7062.6821793019435, 7063.540869843444, 7065.2228254891925, 36624.63375366265, 36972.815135924146, 37103.25793484142, 37191.256174876624, 37241.068973275615, 3586.3185994661194, 3606.945895811565, 3609.729270304738, 3611.2235869287965, 3613.7831730096277, 29311.267218319714, 29496.852515737282, 29563.70436295631, 29570.556441653393, 29694.109176724516, 4855.0, 4988.55103040728, 5012.373453318335, 5032.7735251913755, 5040.243963405482, 201482.0, 203407.0, 206446.0, 207382.0, 214014.0, 51381.48618513842, 51558.22662660063, 51765.48234517682, 51912.74043629833, 52016.479835201164, 53216.73391633017, 53942.46057539453, 53959.19061214085, 54013.189802153, 54224.0, 14015.50945716895, 14177.14937103777, 14237.21695306768, 14244.359003844851, 14322.283885805668, 11202.94398528002, 11205.94397028026, 11216.775664486664, 11226.775464490827, 11322.490487928064, 13601.659958501117, 13671.453141874214, 13682.247476388695, 13706.45174193041, 13876.514321998682, 5255.0, 5294.523492885659, 5332.573394128461, 5340.679559226459, 5386.811461598825, 1077.5743581285349, 1089.8964598363302, 1116.6482557994207, 1130.5308297056795, 1131.055568600228, 25473.745262547138, 25512.744872551408, 25812.09657661973, 25812.48375032489, 25965.480690386088, 1184.1296646964474, 1189.482575079843, 1202.1224506110589, 1220.3044264768996, 1226.068188176974, 13391.866081339062, 13408.396622152026, 13463.2550578215, 13568.796468052988, 13588.0, 246943.0, 247042.0, 247843.0, 247937.0, 248049.0, 57910.42089579134, 58074.12888806671, 58221.70889145527, 58279.708601456725, 58347.4165258342, 1491.8284397294233, 1512.8260250071382, 1513.175319450902, 1522.9771553426485, 1543.3286520363454, 25018.982536067226, 25147.622785657863, 25165.0, 25199.108632870866, 25340.239792806246, 41344.1731165381, 41423.7928810354, 41523.58476415197, 41549.584504155784, 41677.0, 227161.0, 228965.0, 229060.0, 229261.0, 229893.0, 36790.8160459196, 38592.42111368388, 38788.50903418706, 39127.0, 39156.412653809646, 48789.268160978325, 50041.0, 50075.248871267686, 50408.0, 50497.99004019972, 36796.080097997765, 36994.63005369912, 37045.81477092597, 37219.62780372162, 37340.0, 36039.63960360363, 36441.81779091088, 36676.63323366732, 36799.0, 36896.0, 42579.57420425756, 43230.0, 43435.56564434315, 43438.0, 43859.78070109629, 35036.64963350334, 35088.824555878076, 35161.648383515836, 35348.46977295394, 35552.46671299984, 26547.867260663574, 26728.732712672623, 27095.59356609613, 27439.176824695292, 27527.31181720473], "value": 17936.791444079394, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.9765609375626, 603.4237303375277, 605.8519106293572, 610.94306849151, 617.580045569013, 23039.0, 23047.193348232817, 23956.401089972736, 23978.640320395207, 23996.040158393665, 26693.332666683295, 27107.86446067767, 27197.86401067997, 27253.0, 27283.0, 6749.696263668135, 6896.862062758742, 6902.171739391255, 6911.447084233275, 6923.238443771181, 6697.464202863784, 6778.0, 6840.31596840317, 6936.098307220054, 6995.860082798341, 6942.166939967211, 6948.548344357626, 6949.791506254795, 6951.061606683109, 6952.09622749042, 6897.689603967833, 6940.791776246695, 6942.583444993316, 6942.687579058929, 6942.72229110835, 6455.322191169907, 6465.418112369863, 6466.256380516254, 6468.902966455507, 6474.061261117159, 30158.24604384886, 30173.09480715582, 30862.22844428885, 31053.534196987064, 31308.217294567592, 2271.931842044725, 2315.305408377497, 2323.2913961241816, 2323.4423738302853, 2324.3027091872373, 20319.695204571944, 20558.897205514026, 20576.794232057637, 20606.587868242703, 20626.381208563766, 2393.102586530051, 2510.9372265693323, 2519.937001574957, 2528.014074510942, 2538.834975726572, 150275.0, 154140.0, 154495.0, 154514.59198928095, 154732.2263388687, 15564.143972081418, 16338.346466141222, 16461.917690411472, 16465.423710170326, 16762.916185418995, 25595.104171353913, 25655.230343089737, 25660.358491037874, 25696.0, 25761.74238257631, 10590.629327973484, 10592.934377796255, 10598.576056957789, 10786.0, 10826.62106826273, 8629.439086459486, 9797.216222702167, 9803.362781419328, 9853.753656158653, 10002.0, 8693.739187824376, 9800.607975681036, 9828.21374290055, 9966.651167209258, 10121.746956326151, 2916.650001999731, 2973.405318936221, 2974.6876577959433, 2984.701529846998, 3008.879644814183, 1053.9894601054045, 1057.117307048623, 1078.449990504848, 1081.7079388565044, 1086.1582273737915, 21702.131914723235, 21760.34718958434, 21896.23363182313, 21919.232826850985, 22087.447813804785, 1120.8710998235308, 1121.725177331559, 1126.0709914320719, 1127.3686735428187, 1133.6882357351794, 11980.28118312904, 12239.43164110149, 12307.0, 12346.81479777804, 12363.567275145328, 664706.0, 683070.0, 685016.0, 687051.0, 692375.0, 136226.0, 138415.0, 138758.0, 139153.30423347818, 140231.0, 1254.2223821230896, 1283.4481173095658, 1293.1852932652369, 1315.2437348524636, 1319.2414361742037, 23238.55873465415, 23923.880380597984, 24047.398815029763, 24180.0, 24190.51618967646, 66913.99629005471, 66923.66538167278, 67459.66270168617, 69083.30916690768, 70246.94629580458, 84904.57547712221, 86410.56794716237, 86763.0, 87435.56282218803, 87471.56264218893, 13312.46750129984, 13773.104748191336, 15392.076475411512, 15522.301496432914, 16002.199890005686, 24232.636510452005, 24405.291629585758, 24464.388390290384, 24625.261242162764, 24659.63010554879, 19566.510837229183, 20028.69956950676, 20287.79712202918, 20347.389578312675, 20378.79621203769, 16580.0, 16628.3348666052, 16878.57803554921, 16886.40897568579, 16929.153542322587, 20152.798472015093, 20224.19103235854, 20548.28081017157, 20630.17479300871, 20647.8967605161, 15488.380464781734, 15625.687486249984, 15658.295376708304, 15665.60835979064, 15712.842871571595, 17676.734848977532, 19689.606207876048, 20069.29757458482, 20075.0966206524, 20111.195552178335], "value": 13103.170383409386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.7228554289143, 1458.8249410070787, 1460.102037247093, 1473.0204414064647, 1483.6216764724993, 16182.190890455475, 16742.49772506824, 16779.916100419512, 16796.412125575593, 16884.66230675387, 15841.445549405773, 16344.18279086044, 17097.743533847002, 17106.48680539586, 17120.657586848254, 2852.9714702852966, 2907.8982235621756, 2912.0681381957784, 2922.4593450211673, 2923.8391888446126, 2756.2833663247598, 2795.077624383948, 2832.22113918673, 2846.416484620658, 2846.615706879576, 2763.101991852646, 2778.9861050694717, 2796.0633187882086, 2801.075645037143, 2801.6638003439616, 2700.891964321425, 2722.618833363331, 2727.3318037080944, 2728.386113124541, 2728.8089833711647, 2652.2175958092407, 2657.827241229314, 2668.85321307328, 2669.145873320538, 2686.355274734069, 16194.676106477804, 16245.106519141442, 16373.59066023347, 16398.344066237336, 16745.0, 8103.756887293391, 8346.499210047356, 8390.916090839075, 8411.705590304371, 8413.411061225717, 5232.92150617741, 5259.342582177228, 5406.83779486616, 5440.945590544123, 5483.972580137113, 2146.6350720377573, 2155.902984365707, 2163.242864997254, 2174.923877664274, 2198.1537108213292, 28115.29711757202, 28440.857795711097, 29356.853215733998, 29494.70505294941, 29624.555631665542, 7039.507234493536, 7054.541454805421, 7071.752488662872, 7212.531185472927, 7315.122185337794, 18717.438476845717, 18728.812711872706, 18741.250349985847, 18743.90628046851, 18751.812481875008, 8373.288270496954, 8472.618732157067, 8522.914770852336, 8538.231559159705, 8538.658453661908, 7732.149463559004, 7816.452848300678, 7956.681732730626, 7969.123396426388, 7990.68037278514, 5518.448155184449, 5648.152777083388, 5685.289338832646, 5716.514096301861, 5805.216295800013, 1612.6774645071032, 1618.8543031127256, 1630.290823491785, 1633.085472135608, 1636.4108920788506, 918.6968300460832, 931.753085432361, 934.4533447933036, 945.3477100800426, 950.0119875329718, 7918.0, 8040.195980401913, 8076.959615202004, 8105.513669179868, 8126.431149819574, 779.4543819326381, 781.6248200863618, 784.0395515493475, 784.5057613703444, 784.9136594974548, 4845.554179166115, 5468.507834294933, 5472.097103977799, 5488.807891723771, 5678.943210567924, 23180.420489487897, 23317.650235246485, 24188.0, 24299.0, 24362.0, 23099.19152829643, 23249.97750292485, 23268.185613503447, 23314.883425583103, 23388.064477420714, 12108.757824843453, 12700.174488658393, 12788.296643684687, 12809.142674312172, 12817.359132043546, 9448.0, 9571.090746379083, 10014.649487268201, 10076.445795481319, 10388.0, 20596.0, 20676.896615516827, 20713.89643051775, 20904.790952090894, 21019.474513137295, 21184.894075529523, 21372.145114195257, 21426.57146857085, 21564.568708626055, 21755.456113597287, 13180.143290686075, 13501.662458438617, 13516.391762370913, 13520.526781562598, 13522.459101635824, 15054.473093441677, 15295.541133766004, 15421.074735515906, 15460.768088478906, 15501.612459688147, 11573.537058517903, 11623.825642615198, 11730.178887477794, 11976.880231197576, 12006.63980080599, 11480.311181329147, 11501.942490287494, 11929.522819087486, 11972.281663100242, 12035.699107522381, 9989.051040151175, 10124.088832005154, 10144.847827282745, 10173.694789156338, 10195.898041019494, 7589.279018493235, 7700.114486834086, 7700.884486732815, 7711.807204819925, 7727.072751269883, 7981.281684648409, 8013.0, 8027.879581806161, 8096.7166149184495, 8136.308413784895], "value": 7104.980200862202, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.5390921964673, 556.749462741766, 572.6792995922284, 574.2649408756791, 576.2422414524899, 6777.62723050232, 6810.38706516414, 6821.215560210578, 6846.281140480252, 6863.828404289895, 6729.25978142404, 6773.695183716733, 6781.830454238647, 6784.728610855572, 6818.386345228929, 5493.587980901439, 5493.7802487900435, 5519.089350257203, 5543.1408131739645, 5556.666400063976, 5159.355080614923, 5307.628466007381, 5482.0, 5503.20203570482, 5574.0, 5283.339582552181, 5307.124324486455, 5308.734563271821, 5314.468553144693, 5322.946770532303, 5218.66078704883, 5246.527812496894, 5269.6311258211945, 5284.9735751320995, 5295.947040529584, 4667.113248482778, 4688.203005489075, 4780.450248221464, 4782.426108866958, 4806.4712881583, 6685.799426017227, 6697.129373181455, 6716.2276338221245, 6719.664016799141, 6792.660366981631, 6262.373762623702, 6263.686815659199, 6271.404216599416, 6274.843128921768, 6277.686115694197, 7221.747238846668, 7222.133343998701, 7233.89149162763, 7238.782836514913, 7249.855002899965, 5394.460553944614, 5403.972980135113, 5407.783688652449, 5412.91880621791, 5421.756020979065, 7265.527740696838, 7349.522281051714, 7455.912344543536, 7621.923780762177, 7635.694572217105, 5520.861978450571, 5526.253955716007, 5533.972330138324, 5544.889102217933, 5548.833534993957, 5598.720063996784, 5603.719814009283, 5618.381977982419, 5632.436756324417, 5650.830475085754, 6279.591826531261, 6291.081220227783, 6300.117983482316, 6308.810735677937, 6313.2424109107205, 7276.563406195645, 7291.453141014339, 7302.233265507151, 7307.671154798047, 7323.963380183065, 1951.4926119209108, 1955.0420294056094, 1964.8603809790288, 1969.8916559589074, 1976.3478052242726, 1285.3701686173708, 1338.712176881975, 1345.8115863779078, 1351.776642138866, 1358.4566173530666, 6257.78097766576, 6260.248770147611, 6266.436020758154, 6303.684815759194, 6356.745730170742, 7282.453815963718, 7288.817779555554, 7299.781006569811, 7307.0, 7310.671019804122, 6201.503879689614, 6208.937910620926, 6240.719167637467, 6246.3753624637175, 6255.968720156461, 3876.8061596920043, 3902.9804850975565, 3909.6481316681065, 3910.8631197907935, 3935.763854168759, 7125.251848555827, 7142.21435642079, 7158.892616610755, 7165.498415110892, 7182.42540596751, 7570.735024274124, 7582.431317651198, 7583.848323033509, 7593.5823529706395, 7601.733939312208, 7168.247334029956, 7174.390176835028, 7176.892346614805, 7185.461090418135, 7197.856042879113, 7365.742199023009, 7371.226021267689, 7385.630718464163, 7394.482386232912, 7402.851942961218, 7405.740799072007, 7435.591042492499, 7447.851042979219, 7465.776026719207, 7476.803711406149, 6947.861042779015, 6968.407685346802, 6994.0, 7091.2554181810165, 7168.534045287248, 5445.591580631548, 5459.890802184013, 5518.586106042143, 5536.695481748381, 5554.50009499147, 7010.789676309719, 7141.85716285661, 7225.277472252733, 7345.265473452612, 7357.668904899186, 7002.474814389043, 7056.329648683367, 7088.681009354488, 7159.284071592799, 7201.0, 6942.2363540011565, 6964.965175174092, 7003.0, 7142.0, 7158.141023077264, 6819.863602728017, 6977.651117444006, 7030.894536582057, 7052.836282013411, 7144.178419481618, 7114.857702846018, 7134.0, 7141.392981596622, 7145.892811607934, 7156.463265255231, 6904.206016308189, 6904.927307348182, 6905.689243983932, 6963.582185068912, 6970.546914450544], "value": 5532.376678834218, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [975.5658731864319, 979.3732011512631, 1010.3180353261547, 1011.0395124631602, 1017.1049476460715, 19963.0, 20404.183832646693, 20875.791242087573, 20878.791212087912, 21329.466763330925, 19102.52243693908, 19276.518087047833, 19279.13243904024, 19328.3235086772, 19333.130009149587, 1038.5118994072811, 1063.0910571461404, 1070.0904231403322, 1074.4520294649706, 1074.9516271767789, 1085.5766251162054, 1089.215764649451, 1089.2974031749516, 1090.7982023325671, 1091.5142761471147, 1068.4604274841195, 1113.8050841102815, 1116.5589592111112, 1116.9944150279237, 1118.0999295567065, 1102.1568500097433, 1107.9390633515154, 1109.3343993603773, 1110.0176343935595, 1111.83322501625, 818.0142927772021, 818.66434761748, 819.4591569564119, 820.6061090676449, 821.3880658909092, 18191.818081819143, 18264.45206643803, 18295.359662411753, 18478.53803654906, 18880.811191888042, 21023.78976210249, 21083.68374474369, 21617.783822161735, 21639.0, 21778.128874844988, 18266.81265717724, 18373.448796536126, 18480.90759546207, 18618.81381186198, 18693.906530467397, 3908.472356231901, 3952.3676211805973, 3952.9209415811524, 3954.2486927483687, 3954.426608141803, 133571.33214333866, 134015.0, 134797.0, 135016.0, 137220.0, 16899.23953422099, 16948.0, 17427.651446971242, 17464.47606571805, 17668.91165544164, 33043.173920652174, 33471.0, 33601.66398336034, 33746.32507349888, 33895.661043389744, 8489.915100849035, 8503.319734421231, 8505.064442911274, 8596.527191004554, 8605.225529702231, 4421.756803375845, 4493.393391892053, 4498.0, 4498.820047198075, 4500.482444518857, 5674.744636491368, 5793.246877905845, 5811.3026436827, 5844.970775146097, 5893.793717219876, 1082.2856914436434, 1103.5475455063386, 1124.5614210457927, 1126.7014241226082, 1127.7406196574834, 735.7608777147449, 752.0862152484757, 755.1504557372955, 771.4407054885163, 772.8724760414468, 11868.347240901834, 11947.880521194851, 12154.696132596755, 12755.48978040868, 12757.68105797344, 738.4978214813979, 739.0835364148525, 744.556988591787, 752.9924700752922, 761.5012167030604, 5616.634918730269, 5641.153826925994, 5645.774169033275, 6477.222733272038, 6594.0, 136214.0, 137212.0, 140833.5916640841, 142226.28886855498, 147163.0, 114812.0, 116804.0, 120355.39822300832, 121059.39470302592, 122908.38545807214, 95450.52274738582, 97484.51257743666, 103341.48329258306, 104274.0, 108837.99999999841, 13306.467741290628, 13319.733605328032, 13608.591742247749, 13970.79043814364, 14216.360263787947, 39117.2176556473, 48609.270860936405, 49843.50156498388, 50198.0, 51038.0, 65539.751459799, 78137.218627813, 80267.19732802831, 81143.0, 87003.56498217468, 20094.0, 20149.39551813448, 20357.898210508854, 20407.285744998855, 20747.688784668542, 21411.036503357074, 23290.76709232886, 23357.065717371115, 23658.645120322864, 23997.400064997815, 21130.154793808077, 21354.252601158885, 21656.242031528822, 21984.0, 22009.33971980843, 18040.188191531674, 18372.908135459238, 18538.629227415648, 18647.347342842935, 19326.903365483082, 18809.717854232473, 18816.341428049953, 20822.27122050721, 21293.0, 21537.353879383645, 13953.441862325799, 14121.576352709435, 14206.644833879236, 14503.202323871867, 14557.708845822812, 14830.184339861411, 15326.540203793904, 15378.538643840702, 15406.229688515305, 15498.302576383865], "value": 9936.755619161862, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1436.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 111.0, 99.0, 106.0, 105.0, 106.0, 108.0, 98.0, 107.0, 106.0], "value": 104.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 139.0, 175.0, 135.0, 136.0, 139.0, 112.0, 169.0, 112.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 160.0, 161.0, 161.0, 160.0, 161.0, 160.0, 163.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 72.0, 172.0, 74.0, 73.0, 73.0, 73.0, 165.0, 73.0], "value": 92.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 135.0, 77.0, 78.0, 78.0, 125.0, 81.0, 78.0, 128.0], "value": 93.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 115.0, 114.0, 114.0, 118.0, 117.0, 117.0, 114.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 149.0, 149.0, 151.0, 150.0, 149.0, 148.0, 149.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 98.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 44.0, 47.0, 45.0, 43.0, 43.0, 45.0, 54.0, 43.0, 44.0], "value": 45.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [35.0, 37.0, 36.0, 36.0, 37.0, 37.0, 37.0, 38.0, 36.0, 36.0], "value": 36.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 71.0, 72.0, 72.0, 72.0, 74.0, 74.0, 74.0, 73.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 110.0, 109.0, 109.0, 113.0, 113.0, 129.0, 122.0, 107.0, 145.0], "value": 116.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 157.0, 171.0, 157.0, 157.0, 160.0, 155.0, 156.0, 155.0, 158.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 57.0, 59.0, 58.0, 60.0, 62.0, 59.0, 59.0, 58.0], "value": 59.2, "unit": "score"}]}]} 12:13:52 INFO - Return code: 0 12:13:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:13:52 INFO - # TBPL SUCCESS # 12:13:52 INFO - Running post-action listener: _package_coverage_data 12:13:52 INFO - Running post-action listener: _resource_record_post_action 12:13:52 INFO - [mozharness: 2017-09-03 19:13:52.588039Z] Finished run-tests step (success) 12:13:52 INFO - Running post-run listener: _resource_record_post_run 12:13:52 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 6443008; Write bytes: 383430656; Read time: 2916; Write time: 212972 12:13:52 INFO - TinderboxPrint: CPU usage
13.1% 12:13:52 INFO - TinderboxPrint: I/O read bytes / time
6,443,008 / 2,916 12:13:52 INFO - TinderboxPrint: I/O write bytes / time
383,430,656 / 212,972 12:13:52 INFO - TinderboxPrint: CPU idle
8,233.0 (86.9%) 12:13:52 INFO - TinderboxPrint: CPU user
1,187.5 (12.5%) 12:13:52 INFO - TinderboxPrint: Swap in / out
0 / 0 12:13:52 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 8192; Write bytes: 114987008; Read time: 200; Write time: 84960 12:13:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:13:52 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4403200; Write bytes: 266551296; Read time: 2608; Write time: 127876 12:13:53 INFO - Running post-run listener: _upload_blobber_files 12:13:53 INFO - Blob upload gear active. 12:13:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:13:53 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 12:13:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:13:53 INFO - (blobuploader) - INFO - Open directory for files ... 12:13:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:13:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:13:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:13:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:13:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:13:54 INFO - (blobuploader) - INFO - Done attempting. 12:13:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:13:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:13:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:14:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:01 INFO - (blobuploader) - INFO - Done attempting. 12:14:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:14:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:14:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:02 INFO - (blobuploader) - INFO - Done attempting. 12:14:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:14:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:14:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:14:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:14:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:14:03 INFO - (blobuploader) - INFO - Done attempting. 12:14:03 INFO - (blobuploader) - INFO - Iteration through files over. 12:14:03 INFO - Return code: 0 12:14:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:14:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:14:03 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70dcdd2fe570fc36e0de050c73f6858867f885c34c09b5f31ab8f596528106bcbd5cf5d19e46581056207ecbb37ff536e24ec5f4cece417418946406384bc3b8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4859d2648f4a17b56c7d46951c017a2e983691df3614f9af1a91aae1052740adf86633ddeb60ae202dd044ed462117781ddbc0f7a7bf5a2786d1995a531dca91", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac3af2658ae96fd09905683554e05f49e476d8dbed5a6ffa647896a35928689bb2fc80425323a0c8fba6ba5aa5d42efe6d9c3e54e67cbe3ed369894b0de497cc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ad4fdafa8e81cc8cb9eba9ced3d1b4037571ee97989754dc24881e94c11d2c443dfaf849efff24462eac8533f0963035ed69ff34801d9f77e3db6ea0b9e1867"} 12:14:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:14:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:14:03 INFO - Contents: 12:14:03 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70dcdd2fe570fc36e0de050c73f6858867f885c34c09b5f31ab8f596528106bcbd5cf5d19e46581056207ecbb37ff536e24ec5f4cece417418946406384bc3b8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4859d2648f4a17b56c7d46951c017a2e983691df3614f9af1a91aae1052740adf86633ddeb60ae202dd044ed462117781ddbc0f7a7bf5a2786d1995a531dca91", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac3af2658ae96fd09905683554e05f49e476d8dbed5a6ffa647896a35928689bb2fc80425323a0c8fba6ba5aa5d42efe6d9c3e54e67cbe3ed369894b0de497cc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ad4fdafa8e81cc8cb9eba9ced3d1b4037571ee97989754dc24881e94c11d2c443dfaf849efff24462eac8533f0963035ed69ff34801d9f77e3db6ea0b9e1867"} 12:14:03 INFO - Running post-run listener: copy_logs_to_upload_dir 12:14:03 INFO - Copying logs to upload dir... 12:14:03 INFO - mkdir: /builds/slave/test/build/upload/logs 12:14:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.791769 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-03 12:14:03.901867) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 12:14:03.905595) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70dcdd2fe570fc36e0de050c73f6858867f885c34c09b5f31ab8f596528106bcbd5cf5d19e46581056207ecbb37ff536e24ec5f4cece417418946406384bc3b8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4859d2648f4a17b56c7d46951c017a2e983691df3614f9af1a91aae1052740adf86633ddeb60ae202dd044ed462117781ddbc0f7a7bf5a2786d1995a531dca91", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac3af2658ae96fd09905683554e05f49e476d8dbed5a6ffa647896a35928689bb2fc80425323a0c8fba6ba5aa5d42efe6d9c3e54e67cbe3ed369894b0de497cc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ad4fdafa8e81cc8cb9eba9ced3d1b4037571ee97989754dc24881e94c11d2c443dfaf849efff24462eac8533f0963035ed69ff34801d9f77e3db6ea0b9e1867"} build_url:https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011175 build_url: 'https://queue.taskcluster.net/v1/task/euJRTGk7QEaL4RhTVUUjhA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70dcdd2fe570fc36e0de050c73f6858867f885c34c09b5f31ab8f596528106bcbd5cf5d19e46581056207ecbb37ff536e24ec5f4cece417418946406384bc3b8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4859d2648f4a17b56c7d46951c017a2e983691df3614f9af1a91aae1052740adf86633ddeb60ae202dd044ed462117781ddbc0f7a7bf5a2786d1995a531dca91", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac3af2658ae96fd09905683554e05f49e476d8dbed5a6ffa647896a35928689bb2fc80425323a0c8fba6ba5aa5d42efe6d9c3e54e67cbe3ed369894b0de497cc", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ad4fdafa8e81cc8cb9eba9ced3d1b4037571ee97989754dc24881e94c11d2c443dfaf849efff24462eac8533f0963035ed69ff34801d9f77e3db6ea0b9e1867"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 12:14:03.937870) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 12:14:03.938218) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1504463716.924899-1136925605 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 12:14:04.021840) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 12:14:04.022304) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 12:14:04.022754) ========= ========= Total master_lag: 0.23 =========