builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-078 starttime: 1504480464.67 results: success (0) revision: dbf9f7430406ca3220529c5b4c05b26511efa3dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:24.668568) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:24.669327) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:24.669617) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:24.864288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:24.864572) ========= 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=dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-03 16:14:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 107M=0s 2017-09-03 16:14:25 (107 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.308536 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:25.211536) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:25.211818) ========= 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=dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015686 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:25.263627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-03 16:14:25.263934) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dbf9f7430406ca3220529c5b4c05b26511efa3dc --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 dbf9f7430406ca3220529c5b4c05b26511efa3dc --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=dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-03 16:14:25,305 truncating revision to first 12 chars 2017-09-03 16:14:25,305 Setting DEBUG logging. 2017-09-03 16:14:25,305 attempt 1/10 2017-09-03 16:14:25,305 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dbf9f7430406?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-03 16:14:26,034 unpacking tar archive at: mozilla-central-dbf9f7430406/testing/mozharness/ program finished with exit code 0 elapsedTime=1.115246 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-03 16:14:26.401161) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:26.401482) ========= script_repo_revision: dbf9f7430406ca3220529c5b4c05b26511efa3dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:26.402079) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:26.402472) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-03 16:14:26.416034) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-09-03 16:14:26.416299) ========= /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=dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:14:26 INFO - MultiFileLogger online at 20170903 16:14:26 in /builds/slave/test 16:14:26 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 16:14:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:14:26 INFO - {'append_to_log': False, 16:14:26 INFO - 'base_work_dir': '/builds/slave/test', 16:14:26 INFO - 'blob_upload_branch': 'Firefox', 16:14:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:14:26 INFO - 'branch': 'Firefox', 16:14:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:14:26 INFO - 'code_coverage': False, 16:14:26 INFO - 'config_files': ('talos/linux_config.py',), 16:14:26 INFO - 'default_actions': ('clobber', 16:14:26 INFO - 'read-buildbot-config', 16:14:26 INFO - 'download-and-extract', 16:14:26 INFO - 'populate-webroot', 16:14:26 INFO - 'create-virtualenv', 16:14:26 INFO - 'install', 16:14:26 INFO - 'setup-mitmproxy', 16:14:26 INFO - 'run-tests'), 16:14:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:14:26 INFO - 'disable_ccov_upload': False, 16:14:26 INFO - 'disable_stylo': False, 16:14:26 INFO - 'download_minidump_stackwalk': True, 16:14:26 INFO - 'download_symbols': 'ondemand', 16:14:26 INFO - 'enable_stylo': False, 16:14:26 INFO - 'enable_webrender': False, 16:14:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:14:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:14:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:14:26 INFO - 'gecko_profile': False, 16:14:26 INFO - 'gecko_profile_interval': 0, 16:14:26 INFO - 'installer_path': 'installer.exe', 16:14:26 INFO - 'log_level': 'info', 16:14:26 INFO - 'log_name': 'talos', 16:14:26 INFO - 'log_to_console': True, 16:14:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:14:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:14:26 INFO - 'opt_config_files': (), 16:14:26 INFO - 'pip_index': False, 16:14:26 INFO - 'suite': 'dromaeojs-e10s', 16:14:26 INFO - 'system_bits': '32', 16:14:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:14:26 INFO - 'title': 'talos-linux64-ix-078', 16:14:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:14:26 INFO - 'use_talos_json': True, 16:14:26 INFO - 'verify': 'False', 16:14:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:14:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:14:26 INFO - 'work_dir': 'build'} 16:14:26 INFO - [mozharness: 2017-09-03 23:14:26.595787Z] Running clobber step. 16:14:26 INFO - Running pre-action listener: _resource_record_pre_action 16:14:26 INFO - Running main action method: clobber 16:14:26 INFO - rmtree: /builds/slave/test/build 16:14:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:14:28 INFO - Running post-action listener: _resource_record_post_action 16:14:28 INFO - [mozharness: 2017-09-03 23:14:28.116197Z] Finished clobber step (success) 16:14:28 INFO - [mozharness: 2017-09-03 23:14:28.116294Z] Running read-buildbot-config step. 16:14:28 INFO - Running pre-action listener: _resource_record_pre_action 16:14:28 INFO - Running main action method: read_buildbot_config 16:14:28 INFO - Using buildbot properties: 16:14:28 INFO - { 16:14:28 INFO - "project": "", 16:14:28 INFO - "product": "firefox", 16:14:28 INFO - "who": "archaeopteryx@coole-files.de", 16:14:28 INFO - "installer_path": "public/build/target.tar.bz2", 16:14:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:14:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:14:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 16:14:28 INFO - "stage_platform": "linux64-pgo", 16:14:28 INFO - "basedir": "/builds/slave/test", 16:14:28 INFO - "buildnumber": 138, 16:14:28 INFO - "platform": "ubuntu64_hw", 16:14:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:14:28 INFO - "slavebuilddir": "test", 16:14:28 INFO - "taskId": "bz97mV3vR9mjcb6R_d5wRg", 16:14:28 INFO - "branch": "mozilla-central", 16:14:28 INFO - "script_repo_revision": "production", 16:14:28 INFO - "revision": "dbf9f7430406ca3220529c5b4c05b26511efa3dc", 16:14:28 INFO - "slavename": "talos-linux64-ix-078", 16:14:28 INFO - "repo_path": "mozilla-central" 16:14:28 INFO - } 16:14:28 INFO - Finding installer, test and symbols from parent task. 16:14:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bz97mV3vR9mjcb6R_d5wRg'}, attempt #1 16:14:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bz97mV3vR9mjcb6R_d5wRg'}, attempt #1 16:14:29 INFO - Task dependencies: cbMG52T8T9aAD4VkwEjQyA 16:14:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA'}, attempt #1 16:14:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.tar.bz2 16:14:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.test_packages.json 16:14:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.crashreporter-symbols.zip 16:14:29 INFO - Running post-action listener: _resource_record_post_action 16:14:29 INFO - [mozharness: 2017-09-03 23:14:29.538722Z] Finished read-buildbot-config step (success) 16:14:29 INFO - [mozharness: 2017-09-03 23:14:29.538980Z] Running download-and-extract step. 16:14:29 INFO - Running pre-action listener: _resource_record_pre_action 16:14:29 INFO - Running main action method: download_and_extract 16:14:29 INFO - mkdir: /builds/slave/test/build/tests 16:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:29 INFO - trying https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.test_packages.json 16:14:29 INFO - Downloading https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:14:30 INFO - Downloaded 989 bytes. 16:14:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:14:30 INFO - Using the following test package requirements: 16:14:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:14:30 INFO - u'common': [u'target.common.tests.zip'], 16:14:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:14:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:14:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:14:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:14:30 INFO - u'mozbase': [u'target.common.tests.zip'], 16:14:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:14:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:14:30 INFO - u'web-platform': [u'target.common.tests.zip', 16:14:30 INFO - u'target.web-platform.tests.tar.gz'], 16:14:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:14:30 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 16:14:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.common.tests.zip 16:14:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:14:30 INFO - Fetch https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.common.tests.zip into memory 16:14:31 INFO - Content-Length response header: 55685855 16:14:31 INFO - Bytes received: 55685855 16:14:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 16:14:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.common.tests.zip 16:14:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:14:42 INFO - Fetch https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.common.tests.zip into memory 16:14:43 INFO - Content-Length response header: 55685855 16:14:43 INFO - Bytes received: 55685855 16:14:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.talos.tests.zip 16:14:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:14:58 INFO - Fetch https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.talos.tests.zip into memory 16:14:58 INFO - Content-Length response header: 14496650 16:14:58 INFO - Bytes received: 14496650 16:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:14:59 INFO - trying https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.tar.bz2 16:14:59 INFO - Downloading https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:14:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:15:17 INFO - Downloaded 63520447 bytes. 16:15:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.tar.bz2 16:15:17 INFO - mkdir: /builds/slave/test/properties 16:15:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:15:17 INFO - Writing to file /builds/slave/test/properties/build_url 16:15:17 INFO - Contents: 16:15:17 INFO - build_url:https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.tar.bz2 16:15:17 INFO - Running post-action listener: _resource_record_post_action 16:15:17 INFO - Running post-action listener: find_tests_for_verification 16:15:17 INFO - Running post-action listener: set_extra_try_arguments 16:15:17 INFO - [mozharness: 2017-09-03 23:15:17.321990Z] Finished download-and-extract step (success) 16:15:17 INFO - [mozharness: 2017-09-03 23:15:17.322216Z] Running populate-webroot step. 16:15:17 INFO - Running pre-action listener: _resource_record_pre_action 16:15:17 INFO - Running main action method: populate_webroot 16:15:17 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:15:17 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:15:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:15:17 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:15:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:15:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:17 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:15:17 INFO - 'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:15:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:15:17 INFO - 'tests': ['damp', 'tps']}, 16:15:17 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:15:17 INFO - 'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['damp', 'tps']}, 16:15:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:15:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:15:17 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:15:17 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:15:17 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:15:17 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:15:17 INFO - 'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:15:17 INFO - 'other-e10s': {'tests': ['a11yr', 16:15:17 INFO - 'ts_paint', 16:15:17 INFO - 'tpaint', 16:15:17 INFO - 'sessionrestore', 16:15:17 INFO - 'sessionrestore_many_windows', 16:15:17 INFO - 'sessionrestore_no_auto_restore', 16:15:17 INFO - 'tabpaint']}, 16:15:17 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['a11yr', 16:15:17 INFO - 'ts_paint', 16:15:17 INFO - 'tpaint', 16:15:17 INFO - 'sessionrestore', 16:15:17 INFO - 'sessionrestore_many_windows', 16:15:17 INFO - 'sessionrestore_no_auto_restore', 16:15:17 INFO - 'tabpaint']}, 16:15:17 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:15:17 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:15:17 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['bloom_basic_singleton']}, 16:15:17 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['bloom_basic']}, 16:15:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:15:17 INFO - 'tsvgr_opacity', 16:15:17 INFO - 'tart', 16:15:17 INFO - 'tscrollx', 16:15:17 INFO - 'tsvg_static']}, 16:15:17 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['tsvgx', 16:15:17 INFO - 'tsvgr_opacity', 16:15:17 INFO - 'tart', 16:15:17 INFO - 'tscrollx', 16:15:17 INFO - 'tsvg_static']}, 16:15:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:15:17 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:15:17 INFO - 'talos_options': ['--enable-stylo'], 16:15:17 INFO - 'tests': ['tp5o']}, 16:15:17 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:15:17 INFO - 'talos_options': ['--mitmproxy', 16:15:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:15:17 INFO - '--firstNonBlankPaint'], 16:15:17 INFO - 'tests': ['tp6_google', 16:15:17 INFO - 'tp6_youtube', 16:15:17 INFO - 'tp6_amazon', 16:15:17 INFO - 'tp6_facebook']}, 16:15:17 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:15:17 INFO - 'talos_options': ['--enable-stylo', 16:15:17 INFO - '--mitmproxy', 16:15:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:15:17 INFO - '--firstNonBlankPaint'], 16:15:17 INFO - 'tests': ['tp6_google', 16:15:17 INFO - 'tp6_youtube', 16:15:17 INFO - 'tp6_amazon', 16:15:17 INFO - 'tp6_facebook']}, 16:15:17 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:15:17 INFO - 'talos_options': ['--enable-stylo', 16:15:17 INFO - '--stylo-threads=1', 16:15:17 INFO - '--mitmproxy', 16:15:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:15:17 INFO - '--firstNonBlankPaint'], 16:15:17 INFO - 'tests': ['tp6_google', 16:15:17 INFO - 'tp6_youtube', 16:15:17 INFO - 'tp6_amazon', 16:15:17 INFO - 'tp6_facebook']}, 16:15:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:15:17 INFO - 'talos_options': ['--xperf_path', 16:15:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:17 INFO - 'tests': ['tp5n']}, 16:15:17 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 16:15:17 INFO - 'talos_options': ['--enable-stylo', 16:15:17 INFO - '--xperf_path', 16:15:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:15:17 INFO - 'tests': ['tp5n']}}} 16:15:17 INFO - Running post-action listener: _resource_record_post_action 16:15:17 INFO - [mozharness: 2017-09-03 23:15:17.346983Z] Finished populate-webroot step (success) 16:15:17 INFO - [mozharness: 2017-09-03 23:15:17.347072Z] Running create-virtualenv step. 16:15:17 INFO - Running pre-action listener: _resource_record_pre_action 16:15:17 INFO - Running main action method: create_virtualenv 16:15:17 INFO - Creating virtualenv /builds/slave/test/build/venv 16:15:17 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:15:17 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:15:17 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:15:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:15:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:15:17 INFO - Installing distribute.............................................................................................................................................................................................done. 16:15:18 INFO - Installing pip...............done. 16:15:18 INFO - Return code: 0 16:15:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:15:18 INFO - Reading from file tmpfile_stdout 16:15:18 INFO - Output received: 16:15:18 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:15:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:15:18 INFO - Using env: {'DISPLAY': ':0', 16:15:18 INFO - 'HOME': '/home/cltbld', 16:15:18 INFO - 'LANG': 'en_US.UTF-8', 16:15:18 INFO - 'LANGUAGE': 'en_US:en', 16:15:18 INFO - 'LOGNAME': 'cltbld', 16:15:18 INFO - 'MAIL': '/var/mail/cltbld', 16:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:18 INFO - 'MOZ_NO_REMOTE': '1', 16:15:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:18 INFO - 'NO_EM_RESTART': '1', 16:15:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:18 INFO - 'PWD': '/builds/slave/test', 16:15:18 INFO - 'SHELL': '/bin/bash', 16:15:18 INFO - 'SHLVL': '1', 16:15:18 INFO - 'TERM': 'linux', 16:15:18 INFO - 'TMOUT': '86400', 16:15:18 INFO - 'USER': 'cltbld', 16:15:18 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:18 INFO - '_': '/tools/buildbot/bin/python'} 16:15:19 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:15:19 INFO - Downloading/unpacking pip>=1.5 16:15:19 INFO - Running setup.py egg_info for package pip 16:15:19 INFO - warning: no files found matching 'pip/cacert.pem' 16:15:19 INFO - warning: no files found matching '*.html' under directory 'docs' 16:15:19 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:15:19 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:15:19 INFO - Installing collected packages: pip 16:15:19 INFO - Found existing installation: pip 1.1 16:15:19 INFO - Uninstalling pip: 16:15:19 INFO - Successfully uninstalled pip 16:15:19 INFO - Running setup.py install for pip 16:15:19 INFO - warning: no files found matching 'pip/cacert.pem' 16:15:19 INFO - warning: no files found matching '*.html' under directory 'docs' 16:15:19 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:15:19 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:15:19 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:15:19 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:15:19 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:15:19 INFO - Successfully installed pip 16:15:19 INFO - Cleaning up... 16:15:19 INFO - Return code: 0 16:15:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:15:19 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')]} 16:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:19 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')]} 16:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:19 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 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:19 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 16:15:19 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 16:15:19 INFO - Using env: {'DISPLAY': ':0', 16:15:19 INFO - 'HOME': '/home/cltbld', 16:15:19 INFO - 'LANG': 'en_US.UTF-8', 16:15:19 INFO - 'LANGUAGE': 'en_US:en', 16:15:19 INFO - 'LOGNAME': 'cltbld', 16:15:19 INFO - 'MAIL': '/var/mail/cltbld', 16:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:19 INFO - 'MOZ_NO_REMOTE': '1', 16:15:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:19 INFO - 'NO_EM_RESTART': '1', 16:15:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:19 INFO - 'PWD': '/builds/slave/test', 16:15:19 INFO - 'SHELL': '/bin/bash', 16:15:19 INFO - 'SHLVL': '1', 16:15:19 INFO - 'TERM': 'linux', 16:15:19 INFO - 'TMOUT': '86400', 16:15:19 INFO - 'USER': 'cltbld', 16:15:19 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:19 INFO - '_': '/tools/buildbot/bin/python'} 16:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:19 INFO - Downloading/unpacking psutil>=3.1.1 16:15:19 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 16:15:19 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 16:15:19 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 16:15:19 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 16:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:15:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:20 INFO - Installing collected packages: psutil 16:15:20 INFO - Running setup.py install for psutil 16:15:21 INFO - building 'psutil._psutil_linux' extension 16:15:21 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 16:15:21 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 16:15:21 INFO - building 'psutil._psutil_posix' extension 16:15:21 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 16:15:21 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 16:15:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:21 INFO - Successfully installed psutil 16:15:21 INFO - Cleaning up... 16:15:21 INFO - Return code: 0 16:15:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:15:21 INFO - Using env: {'DISPLAY': ':0', 16:15:21 INFO - 'HOME': '/home/cltbld', 16:15:21 INFO - 'LANG': 'en_US.UTF-8', 16:15:21 INFO - 'LANGUAGE': 'en_US:en', 16:15:21 INFO - 'LOGNAME': 'cltbld', 16:15:21 INFO - 'MAIL': '/var/mail/cltbld', 16:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:21 INFO - 'MOZ_NO_REMOTE': '1', 16:15:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:21 INFO - 'NO_EM_RESTART': '1', 16:15:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:21 INFO - 'PWD': '/builds/slave/test', 16:15:21 INFO - 'SHELL': '/bin/bash', 16:15:21 INFO - 'SHLVL': '1', 16:15:21 INFO - 'TERM': 'linux', 16:15:21 INFO - 'TMOUT': '86400', 16:15:21 INFO - 'USER': 'cltbld', 16:15:21 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:21 INFO - '_': '/tools/buildbot/bin/python'} 16:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:15:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:15:22 INFO - Installing collected packages: mozsystemmonitor 16:15:22 INFO - Running setup.py install for mozsystemmonitor 16:15:22 INFO - Successfully installed mozsystemmonitor 16:15:22 INFO - Cleaning up... 16:15:22 INFO - Return code: 0 16:15:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:15:22 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')]} 16:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:22 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')]} 16:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:22 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 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:22 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 16:15:22 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 16:15:22 INFO - Using env: {'DISPLAY': ':0', 16:15:22 INFO - 'HOME': '/home/cltbld', 16:15:22 INFO - 'LANG': 'en_US.UTF-8', 16:15:22 INFO - 'LANGUAGE': 'en_US:en', 16:15:22 INFO - 'LOGNAME': 'cltbld', 16:15:22 INFO - 'MAIL': '/var/mail/cltbld', 16:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:22 INFO - 'MOZ_NO_REMOTE': '1', 16:15:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:22 INFO - 'NO_EM_RESTART': '1', 16:15:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:22 INFO - 'PWD': '/builds/slave/test', 16:15:22 INFO - 'SHELL': '/bin/bash', 16:15:22 INFO - 'SHLVL': '1', 16:15:22 INFO - 'TERM': 'linux', 16:15:22 INFO - 'TMOUT': '86400', 16:15:22 INFO - 'USER': 'cltbld', 16:15:22 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:22 INFO - '_': '/tools/buildbot/bin/python'} 16:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:23 INFO - Downloading/unpacking jsonschema==2.5.1 16:15:23 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 16:15:23 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 16:15:23 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 16:15:23 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 16:15:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:15:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:15:24 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 16:15:24 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 16:15:24 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 16:15:24 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 16:15:24 INFO - Downloading functools32-3.2.3-2.tar.gz 16:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:15:24 INFO - warning: no files found matching '*.txt' 16:15:24 INFO - no previously-included directories found matching 'build' 16:15:24 INFO - no previously-included directories found matching 'dist' 16:15:24 INFO - no previously-included directories found matching '.git*' 16:15:24 INFO - Installing collected packages: jsonschema, functools32 16:15:24 INFO - Running setup.py install for functools32 16:15:24 INFO - warning: no files found matching '*.txt' 16:15:24 INFO - no previously-included directories found matching 'build' 16:15:24 INFO - no previously-included directories found matching 'dist' 16:15:24 INFO - no previously-included directories found matching '.git*' 16:15:24 INFO - Successfully installed jsonschema functools32 16:15:24 INFO - Cleaning up... 16:15:24 INFO - Return code: 0 16:15:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:15:24 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')]} 16:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:24 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')]} 16:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:24 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 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:24 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 16:15:24 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 16:15:24 INFO - Using env: {'DISPLAY': ':0', 16:15:24 INFO - 'HOME': '/home/cltbld', 16:15:24 INFO - 'LANG': 'en_US.UTF-8', 16:15:24 INFO - 'LANGUAGE': 'en_US:en', 16:15:24 INFO - 'LOGNAME': 'cltbld', 16:15:24 INFO - 'MAIL': '/var/mail/cltbld', 16:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:24 INFO - 'MOZ_NO_REMOTE': '1', 16:15:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:24 INFO - 'NO_EM_RESTART': '1', 16:15:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:24 INFO - 'PWD': '/builds/slave/test', 16:15:24 INFO - 'SHELL': '/bin/bash', 16:15:24 INFO - 'SHLVL': '1', 16:15:24 INFO - 'TERM': 'linux', 16:15:24 INFO - 'TMOUT': '86400', 16:15:24 INFO - 'USER': 'cltbld', 16:15:24 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:24 INFO - '_': '/tools/buildbot/bin/python'} 16:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:15:24 INFO - Cleaning up... 16:15:24 INFO - Return code: 0 16:15:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:15:24 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')]} 16:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:24 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')]} 16:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:24 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 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:24 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 16:15:24 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 16:15:24 INFO - Using env: {'DISPLAY': ':0', 16:15:24 INFO - 'HOME': '/home/cltbld', 16:15:24 INFO - 'LANG': 'en_US.UTF-8', 16:15:24 INFO - 'LANGUAGE': 'en_US:en', 16:15:24 INFO - 'LOGNAME': 'cltbld', 16:15:24 INFO - 'MAIL': '/var/mail/cltbld', 16:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:24 INFO - 'MOZ_NO_REMOTE': '1', 16:15:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:24 INFO - 'NO_EM_RESTART': '1', 16:15:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:24 INFO - 'PWD': '/builds/slave/test', 16:15:24 INFO - 'SHELL': '/bin/bash', 16:15:24 INFO - 'SHLVL': '1', 16:15:24 INFO - 'TERM': 'linux', 16:15:24 INFO - 'TMOUT': '86400', 16:15:24 INFO - 'USER': 'cltbld', 16:15:24 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:24 INFO - '_': '/tools/buildbot/bin/python'} 16:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:25 INFO - Downloading/unpacking blobuploader==1.2.4 16:15:25 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 16:15:25 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 16:15:25 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 16:15:25 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 16:15:26 INFO - Downloading blobuploader-1.2.4.tar.gz 16:15:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:15:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:15:26 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 16:15:26 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 16:15:26 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 16:15:26 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 16:15:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:15:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:15:26 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 16:15:26 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 16:15:26 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 16:15:26 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 16:15:26 INFO - Downloading docopt-0.6.1.tar.gz 16:15:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:15:26 INFO - Installing collected packages: blobuploader, requests, docopt 16:15:26 INFO - Running setup.py install for blobuploader 16:15:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:15:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:15:26 INFO - Running setup.py install for requests 16:15:27 INFO - Running setup.py install for docopt 16:15:27 INFO - Successfully installed blobuploader requests docopt 16:15:27 INFO - Cleaning up... 16:15:27 INFO - Return code: 0 16:15:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:27 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')]} 16:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:27 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')]} 16:15:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:27 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 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:27 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 16:15:27 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 16:15:27 INFO - Using env: {'DISPLAY': ':0', 16:15:27 INFO - 'HOME': '/home/cltbld', 16:15:27 INFO - 'LANG': 'en_US.UTF-8', 16:15:27 INFO - 'LANGUAGE': 'en_US:en', 16:15:27 INFO - 'LOGNAME': 'cltbld', 16:15:27 INFO - 'MAIL': '/var/mail/cltbld', 16:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:27 INFO - 'MOZ_NO_REMOTE': '1', 16:15:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:27 INFO - 'NO_EM_RESTART': '1', 16:15:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:27 INFO - 'PWD': '/builds/slave/test', 16:15:27 INFO - 'SHELL': '/bin/bash', 16:15:27 INFO - 'SHLVL': '1', 16:15:27 INFO - 'TERM': 'linux', 16:15:27 INFO - 'TMOUT': '86400', 16:15:27 INFO - 'USER': 'cltbld', 16:15:27 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:27 INFO - '_': '/tools/buildbot/bin/python'} 16:15:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:27 INFO - Running setup.py (path:/tmp/pip-6LzsvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:27 INFO - Running setup.py (path:/tmp/pip-Bc7Qir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:27 INFO - Running setup.py (path:/tmp/pip-Dg3XuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:27 INFO - Running setup.py (path:/tmp/pip-EoPdtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:27 INFO - Running setup.py (path:/tmp/pip-XdnzMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:27 INFO - Running setup.py (path:/tmp/pip-adEM33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:28 INFO - Running setup.py (path:/tmp/pip-DOKDNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:28 INFO - Running setup.py (path:/tmp/pip-Q9xypY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:28 INFO - Running setup.py (path:/tmp/pip-VAoXE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:28 INFO - Running setup.py (path:/tmp/pip-30rZsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:28 INFO - Running setup.py (path:/tmp/pip-dT8lPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:28 INFO - Running setup.py (path:/tmp/pip-fSrsxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:28 INFO - Running setup.py (path:/tmp/pip-f6PZhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:28 INFO - Running setup.py (path:/tmp/pip-pE30R3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:28 INFO - Running setup.py (path:/tmp/pip-cTXGK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:28 INFO - Running setup.py (path:/tmp/pip-5doN72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:28 INFO - Running setup.py (path:/tmp/pip-LnQkgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:15:28 INFO - Running setup.py install for manifestparser 16:15:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:15:28 INFO - Running setup.py install for mozcrash 16:15:29 INFO - Running setup.py install for mozdebug 16:15:29 INFO - Running setup.py install for mozdevice 16:15:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for mozfile 16:15:29 INFO - Running setup.py install for mozhttpd 16:15:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for mozinfo 16:15:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for mozInstall 16:15:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for mozleak 16:15:29 INFO - Running setup.py install for mozlog 16:15:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for moznetwork 16:15:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:15:29 INFO - Running setup.py install for mozprocess 16:15:30 INFO - Running setup.py install for mozprofile 16:15:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:15:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:15:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:15:30 INFO - Running setup.py install for mozrunner 16:15:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:15:30 INFO - Running setup.py install for mozscreenshot 16:15:30 INFO - Running setup.py install for moztest 16:15:30 INFO - Running setup.py install for mozversion 16:15:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:15:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:15:30 INFO - Cleaning up... 16:15:30 INFO - Return code: 0 16:15:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:30 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')]} 16:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:30 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')]} 16:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:30 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 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:30 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 16:15:30 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 16:15:30 INFO - Using env: {'DISPLAY': ':0', 16:15:30 INFO - 'HOME': '/home/cltbld', 16:15:30 INFO - 'LANG': 'en_US.UTF-8', 16:15:30 INFO - 'LANGUAGE': 'en_US:en', 16:15:30 INFO - 'LOGNAME': 'cltbld', 16:15:30 INFO - 'MAIL': '/var/mail/cltbld', 16:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:30 INFO - 'MOZ_NO_REMOTE': '1', 16:15:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:30 INFO - 'NO_EM_RESTART': '1', 16:15:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:30 INFO - 'PWD': '/builds/slave/test', 16:15:30 INFO - 'SHELL': '/bin/bash', 16:15:30 INFO - 'SHLVL': '1', 16:15:30 INFO - 'TERM': 'linux', 16:15:30 INFO - 'TMOUT': '86400', 16:15:30 INFO - 'USER': 'cltbld', 16:15:30 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:30 INFO - '_': '/tools/buildbot/bin/python'} 16:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:30 INFO - Running setup.py (path:/tmp/pip-R63A2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:30 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)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:30 INFO - Running setup.py (path:/tmp/pip-uUIDr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:30 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)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:30 INFO - Running setup.py (path:/tmp/pip-GjsvHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:30 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)) 16:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:30 INFO - Running setup.py (path:/tmp/pip-vW8kAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:31 INFO - Running setup.py (path:/tmp/pip-OTLY4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:31 INFO - Running setup.py (path:/tmp/pip-UtD40d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:31 INFO - Running setup.py (path:/tmp/pip-oNPFl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:31 INFO - Running setup.py (path:/tmp/pip-QPLg2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:31 INFO - Running setup.py (path:/tmp/pip-R24SZy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:31 INFO - Running setup.py (path:/tmp/pip-BesaYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:31 INFO - Running setup.py (path:/tmp/pip-yjCb0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:31 INFO - Running setup.py (path:/tmp/pip-qc22jD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:31 INFO - Running setup.py (path:/tmp/pip-uMa_0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:31 INFO - Running setup.py (path:/tmp/pip-25nYv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:31 INFO - Running setup.py (path:/tmp/pip-gIrfdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:31 INFO - Running setup.py (path:/tmp/pip-gOlCCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:31 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)) 16:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:31 INFO - Running setup.py (path:/tmp/pip-6DLVQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:32 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)) 16:15:32 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)) 16:15:32 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)) 16:15:32 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)) 16:15:32 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)) 16:15:32 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)) 16:15:32 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)) 16:15:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:15:32 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 16:15:32 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 16:15:32 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 16:15:32 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 16:15:33 INFO - Downloading blessings-1.6.tar.gz 16:15:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:15:33 INFO - Installing collected packages: blessings 16:15:33 INFO - Running setup.py install for blessings 16:15:33 INFO - Successfully installed blessings 16:15:33 INFO - Cleaning up... 16:15:33 INFO - Return code: 0 16:15:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:15:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:33 INFO - Reading from file tmpfile_stdout 16:15:33 INFO - Current package versions: 16:15:33 INFO - argparse == 1.2.1 16:15:33 INFO - blessings == 1.6 16:15:33 INFO - blobuploader == 1.2.4 16:15:33 INFO - docopt == 0.6.1 16:15:33 INFO - functools32 == 3.2.3-2 16:15:33 INFO - jsonschema == 2.5.1 16:15:33 INFO - manifestparser == 1.1 16:15:33 INFO - mozInstall == 1.13 16:15:33 INFO - mozcrash == 1.0 16:15:33 INFO - mozdebug == 0.1 16:15:33 INFO - mozdevice == 0.51 16:15:33 INFO - mozfile == 1.2 16:15:33 INFO - mozhttpd == 0.7 16:15:33 INFO - mozinfo == 0.10 16:15:33 INFO - mozleak == 0.1 16:15:33 INFO - mozlog == 3.5 16:15:33 INFO - moznetwork == 0.27 16:15:33 INFO - mozprocess == 0.25 16:15:33 INFO - mozprofile == 0.28 16:15:33 INFO - mozrunner == 6.13 16:15:33 INFO - mozscreenshot == 0.1 16:15:33 INFO - mozsystemmonitor == 0.3 16:15:33 INFO - moztest == 0.8 16:15:33 INFO - mozversion == 1.4 16:15:33 INFO - psutil == 3.1.1 16:15:33 INFO - requests == 1.2.3 16:15:33 INFO - wsgiref == 0.1.2 16:15:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:33 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')]} 16:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:33 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')]} 16:15:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:33 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 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:33 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 16:15:33 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 16:15:33 INFO - Using env: {'DISPLAY': ':0', 16:15:33 INFO - 'HOME': '/home/cltbld', 16:15:33 INFO - 'LANG': 'en_US.UTF-8', 16:15:33 INFO - 'LANGUAGE': 'en_US:en', 16:15:33 INFO - 'LOGNAME': 'cltbld', 16:15:33 INFO - 'MAIL': '/var/mail/cltbld', 16:15:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:33 INFO - 'MOZ_NO_REMOTE': '1', 16:15:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:33 INFO - 'NO_EM_RESTART': '1', 16:15:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:33 INFO - 'PWD': '/builds/slave/test', 16:15:33 INFO - 'SHELL': '/bin/bash', 16:15:33 INFO - 'SHLVL': '1', 16:15:33 INFO - 'TERM': 'linux', 16:15:33 INFO - 'TMOUT': '86400', 16:15:33 INFO - 'USER': 'cltbld', 16:15:33 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:33 INFO - '_': '/tools/buildbot/bin/python'} 16:15:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 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)) 16:15:33 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:15:33 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 16:15:33 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 16:15:33 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 16:15:33 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 16:15:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:15:34 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)) 16:15:34 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)) 16:15:34 INFO - Installing collected packages: simplejson 16:15:34 INFO - Running setup.py install for simplejson 16:15:35 INFO - building 'simplejson._speedups' extension 16:15:35 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 16:15:35 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 16:15:35 INFO - Successfully installed simplejson 16:15:35 INFO - Cleaning up... 16:15:35 INFO - Return code: 0 16:15:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:15:35 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')]} 16:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:35 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')]} 16:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:35 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 0x10d81f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12a1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1444a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, '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': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', '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 16:15:35 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 16:15:35 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 16:15:35 INFO - Using env: {'DISPLAY': ':0', 16:15:35 INFO - 'HOME': '/home/cltbld', 16:15:35 INFO - 'LANG': 'en_US.UTF-8', 16:15:35 INFO - 'LANGUAGE': 'en_US:en', 16:15:35 INFO - 'LOGNAME': 'cltbld', 16:15:35 INFO - 'MAIL': '/var/mail/cltbld', 16:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:35 INFO - 'MOZ_NO_REMOTE': '1', 16:15:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:35 INFO - 'NO_EM_RESTART': '1', 16:15:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:35 INFO - 'PWD': '/builds/slave/test', 16:15:35 INFO - 'SHELL': '/bin/bash', 16:15:35 INFO - 'SHLVL': '1', 16:15:35 INFO - 'TERM': 'linux', 16:15:35 INFO - 'TMOUT': '86400', 16:15:35 INFO - 'USER': 'cltbld', 16:15:35 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:35 INFO - '_': '/tools/buildbot/bin/python'} 16:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:15:36 INFO - Cleaning up... 16:15:36 INFO - Return code: 0 16:15:36 INFO - Running post-action listener: _resource_record_post_action 16:15:36 INFO - Running post-action listener: _start_resource_monitoring 16:15:36 INFO - Starting resource monitoring. 16:15:36 INFO - [mozharness: 2017-09-03 23:15:36.247597Z] Finished create-virtualenv step (success) 16:15:36 INFO - [mozharness: 2017-09-03 23:15:36.248067Z] Running install step. 16:15:36 INFO - Running pre-action listener: _resource_record_pre_action 16:15:36 INFO - Running main action method: install 16:15:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:36 INFO - Reading from file tmpfile_stdout 16:15:36 INFO - Detecting whether we're running mozinstall >=1.0... 16:15:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:15:36 INFO - Reading from file tmpfile_stdout 16:15:36 INFO - Output received: 16:15:36 INFO - Usage: mozinstall [options] installer 16:15:36 INFO - Options: 16:15:36 INFO - -h, --help show this help message and exit 16:15:36 INFO - -d DEST, --destination=DEST 16:15:36 INFO - Directory to install application into. [default: 16:15:36 INFO - "/builds/slave/test"] 16:15:36 INFO - --app=APP Application being installed. [default: firefox] 16:15:36 INFO - mkdir: /builds/slave/test/build/application 16:15:36 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'] 16:15:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:15:52 INFO - Reading from file tmpfile_stdout 16:15:52 INFO - Output received: 16:15:52 INFO - /builds/slave/test/build/application/firefox/firefox 16:15:52 INFO - Running post-action listener: _resource_record_post_action 16:15:52 INFO - [mozharness: 2017-09-03 23:15:52.985906Z] Finished install step (success) 16:15:52 INFO - [mozharness: 2017-09-03 23:15:52.986157Z] Running setup-mitmproxy step. 16:15:52 INFO - Running pre-action listener: _resource_record_pre_action 16:15:52 INFO - Running main action method: setup_mitmproxy 16:15:52 INFO - Skipping: mitmproxy is not required 16:15:52 INFO - Running post-action listener: _resource_record_post_action 16:15:52 INFO - [mozharness: 2017-09-03 23:15:52.987354Z] Finished setup-mitmproxy step (success) 16:15:52 INFO - [mozharness: 2017-09-03 23:15:52.987572Z] Running run-tests step. 16:15:52 INFO - Running pre-action listener: _resource_record_pre_action 16:15:52 INFO - Running pre-action listener: _set_gcov_prefix 16:15:52 INFO - Running main action method: run_tests 16:15:52 WARNING - Try message not found. 16:15:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:15:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:15:52 INFO - Python 2.7.3 16:15:52 INFO - Return code: 0 16:15:52 INFO - grabbing minidump binary from tooltool 16:15: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')]} 16:15:52 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 0x1448090>, '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 0x1448d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1442f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:15:52 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 16:15:52 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 16:15:52 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 16:15:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:15:53 INFO - Return code: 0 16:15:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:15:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:15:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:15:53 INFO - ENV: RUST_BACKTRACE is now full 16:15:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:15:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:15:53 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/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--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 16:15:53 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/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-078 --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 16:15:53 INFO - Using env: {'DISPLAY': ':0', 16:15:53 INFO - 'HOME': '/home/cltbld', 16:15:53 INFO - 'LANG': 'en_US.UTF-8', 16:15:53 INFO - 'LANGUAGE': 'en_US:en', 16:15:53 INFO - 'LOGNAME': 'cltbld', 16:15:53 INFO - 'MAIL': '/var/mail/cltbld', 16:15:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:15:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:53 INFO - 'MOZ_NO_REMOTE': '1', 16:15:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:15:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:53 INFO - 'NO_EM_RESTART': '1', 16:15:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:53 INFO - 'PWD': '/builds/slave/test', 16:15:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:15:53 INFO - 'RUST_BACKTRACE': 'full', 16:15:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:15:53 INFO - 'SHELL': '/bin/bash', 16:15:53 INFO - 'SHLVL': '1', 16:15:53 INFO - 'TERM': 'linux', 16:15:53 INFO - 'TMOUT': '86400', 16:15:53 INFO - 'USER': 'cltbld', 16:15:53 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:53 INFO - '_': '/tools/buildbot/bin/python'} 16:15:53 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/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-078', '--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 16:15:53 INFO - mozversion application_buildid: 20170903215607 16:15:53 INFO - mozversion application_changeset: dbf9f7430406ca3220529c5b4c05b26511efa3dc 16:15:53 INFO - mozversion application_display_name: Nightly 16:15:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:15:53 INFO - mozversion application_name: Firefox 16:15:53 INFO - mozversion application_remotingname: firefox 16:15:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:15:53 INFO - mozversion application_vendor: Mozilla 16:15:53 INFO - mozversion application_version: 57.0a1 16:15:53 INFO - mozversion platform_buildid: 20170903215607 16:15:53 INFO - mozversion platform_changeset: dbf9f7430406ca3220529c5b4c05b26511efa3dc 16:15:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:15:53 INFO - mozversion platform_version: 57.0a1 16:15:53 INFO - using testdate: 1504480553 16:15:53 INFO - actual date: 1504480553 16:15:53 INFO - starting webserver on 'localhost:47750' 16:15:53 INFO - SUITE-START | Running 2 tests 16:15:53 INFO - TEST-START | dromaeo_css 16:15:53 INFO - Initialising browser for dromaeo_css test... 16:15:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47750/getInfo.html -profile /tmp/tmp3fLTnr/profile 16:15:53 INFO - TEST-INFO | started process 12440 (/builds/slave/test/build/application/firefox/firefox http://localhost:47750/getInfo.html) 16:15:58 INFO - TEST-INFO | 12440: exit 0 16:15:58 INFO - Browser initialized. 16:15:58 INFO - Running cycle 1/1 for dromaeo_css test... 16:15:58 INFO - Using env: {'DISPLAY': ':0', 16:15:58 INFO - 'HOME': '/home/cltbld', 16:15:58 INFO - 'JSGC_DISABLE_POISONING': '1', 16:15:58 INFO - 'LANG': 'en_US.UTF-8', 16:15:58 INFO - 'LANGUAGE': 'en_US:en', 16:15:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:15:58 INFO - 'LOGNAME': 'cltbld', 16:15:58 INFO - 'MAIL': '/var/mail/cltbld', 16:15:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:15:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:15:58 INFO - 'MOZ_CRASHREPORTER': '1', 16:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:15:58 INFO - 'MOZ_NO_REMOTE': '1', 16:15:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:15:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:58 INFO - 'NO_EM_RESTART': '1', 16:15:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:58 INFO - 'PWD': '/builds/slave/test', 16:15:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:15:58 INFO - 'RUST_BACKTRACE': 'full', 16:15:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:15:58 INFO - 'SHELL': '/bin/bash', 16:15:58 INFO - 'SHLVL': '1', 16:15:58 INFO - 'TERM': 'linux', 16:15:58 INFO - 'TMOUT': '86400', 16:15:58 INFO - 'USER': 'cltbld', 16:15:58 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:58 INFO - '_': '/tools/buildbot/bin/python'} 16:15:58 INFO - TEST-INFO | started process 12627 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3fLTnr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:15:59 INFO - PID 12627 | 16:15:59 INFO - PID 12627 | (/builds/slave/test/build/application/firefox/firefox:12679): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:15:59 INFO - PID 12627 | 16:16:10 INFO - PID 12627 | RSS: Main: 162996224 16:16:10 INFO - PID 12627 | 16:19:08 INFO - PID 12627 | Cycle 1(1): loaded http://localhost:47750/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47750/tests/dromaeo/cssquery-ext.html) 16:19:10 INFO - PID 12627 | RSS: Main: 165416960 16:19:10 INFO - PID 12627 | 16:22:12 INFO - PID 12627 | Cycle 1(1): loaded http://localhost:47750/tests/dromaeo/cssquery-ext.html (next: http://localhost:47750/tests/dromaeo/cssquery-jquery.html) 16:22:12 INFO - PID 12627 | RSS: Main: 161431552 16:22:12 INFO - PID 12627 | 16:25:14 INFO - PID 12627 | Cycle 1(1): loaded http://localhost:47750/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47750/tests/dromaeo/cssquery-mootools.html) 16:25:14 INFO - PID 12627 | RSS: Main: 162918400 16:25:14 INFO - PID 12627 | 16:28:16 INFO - PID 12627 | Cycle 1(1): loaded http://localhost:47750/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47750/tests/dromaeo/cssquery-prototype.html) 16:28:16 INFO - PID 12627 | RSS: Main: 162013184 16:28:16 INFO - PID 12627 | 16:31:20 INFO - PID 12627 | Cycle 1(1): loaded http://localhost:47750/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47750/tests/dromaeo/cssquery-yui.html) 16:31:21 INFO - PID 12627 | RSS: Main: 163201024 16:31:21 INFO - PID 12627 | 16:34:22 INFO - PID 12627 | Cycle 1(1): loaded http://localhost:47750/tests/dromaeo/cssquery-yui.html (next: http://localhost:47750/tests/dromaeo/cssquery-dojo.html) 16:34:22 INFO - PID 12627 | RSS: Main: 165871616 16:34:22 INFO - PID 12627 | 16:34:22 INFO - PID 12627 | __start_tp_report 16:34:22 INFO - PID 12627 | _x_x_mozilla_page_load 16:34:22 INFO - PID 12627 | _x_x_mozilla_page_load_details 16:34:22 INFO - PID 12627 | |i|pagename|runs| 16:34:22 INFO - PID 12627 | |0;dojo.html;3862.439946207798;3896.76619402836;3904.3362628353184;3908.3941988991705;3934.468846705696;6627.4698024158015;6737.865242695143;6758.932410675891;6776.491763117761;6800.217974932884;6647.601143931368;6741.730330786762;6743.763968261112;6747.460203183746;6752.088468056811;5998.790042348519;6003.219581454405;6008.519318454513;6040.788572399967;6047.848803779897;6058.363871793443;6059.212302400681;6059.909101363461;6073.362296958822;6076.848078798043;6048.274207095133;6082.543809214305;6116.694165291718;6138.969305153468;6202.534809889254;6060.696965151769;6067.150598916155;6069.635821850659;6082.361352058058;6160.1991741073825;5861.238039054938;5940.910886336708;5949.970250148722;5961.135635332896;6034.879302413927;6701.396874281338;6720.6639668016405;6744.359285867835;6791;6810;7265.745698900566;7280.162781280114;7375.151857536344;7385.113786345619;7387.150477695081;7229.710811567531;7316;7341.559506429578;7383.2985866342615;7444.9627751861435;6820.897686534706;6849.897251541181;6888.758893438706;6906.861862762717;6908.585484870924;7480.438967077382;7506.774796756106;7507.436942229355;7584.54492730438;7588.089429268523;7081.256468070881;7166.068411106523;7192.856142877114;7238.420926325882;7318.085239345082;7301.306375894282;7302.707891684379;7303.89044164338;7317.524360916524;7320.011798407147;7165.104361954755;7228.927710722825;7251.782446526613;7258.02016727735;7259.89110163348;7274.8181295466975;7320.304571065741;7321.304476074764;7335.88996165058;7365.116186057708;6085.817425477243;6163.044728067067;6165.568410211242;6174.104754810527;6253.499720022478;5329.7068661224;5336.973315133399;5397.379301380392;5441.945580544223;5443.700596467153;6191.318954914956;6292.905606415908;6336.112944187817;6354;6355;7217.170025447036;7301.671424785791;7304.671289791972;7318.780436586911;7334.596597187206;6249.0938813871735;6263.780767673201;6266.717997690115;6335.556511044275;6348.016057511093;6500.252470965805;6595.8021259362295;6615.6030638161865;6690.899636505456;6707.463402927754;6942.895856562156;7021.754238601625;7023.403010744144;7025.894611580728;7040.6479676016;7166.570005799668;7332;7350.117985841734;7351.228121047427;7352.191258961617;7048.859022819617;7060.399866011447;7116.644167791694;7139.750108746169;7152.570845749272;7082.822929426806;7152.892706609509;7166.820829479097;7189.137303523401;7275.126984761862;7265.382442492236;7265.4550908681;7272.818179545554;7284.162321333115;7290.088738907637;6896.655167241718;6990;6990.720371185095;7003.7548685795755;7005.579665220103;6982.511224214256;6983.406410455168;6987.091678081817;6994.895076573957;7006.579605223703;6884.449244060564;6908.965455172692;6985.26654701249;7060;7080.893786593102;7047.788566343018;7049.471289653194;7080.787576372717;7130.5365151265005;7177;6715.932840671726;6850.109485766819;6852.520323577502;6858.828529286808;6859.348361905611 16:34:22 INFO - PID 12627 | |1;ext.html;881.3830318776854;929.284450972751;961.7307153996882;987.7135630667107;991.861139440478;41274;42024.57975420249;42082.78958605203;42111.57888421115;42147.367789483185;38746.80626596863;39003;39018.60981390192;39035.219295614144;39038.80480597593;7269.600171990552;7410.777676669709;7414.518056326321;7417.740379086733;7425.257474252586;7178.5692858428665;7240.710371585156;7244.963775181117;7253.057102576658;7253.238409966957;7237.167725711558;7870.2916737493615;7988.121306656263;8016.919830801705;8017.919820801805;7914.643841027168;7980.640871160841;7983.680652773882;7987.161348058457;7989.2809647132035;7287.489875708702;7660.195679453633;7816.570088645122;7825.452218344718;7833.882491762628;39175.8041209795;39225.21549569021;39287;39554.40668389977;39659.20681586352;3640.0899775056237;3708.332500149972;3709.146896213859;3711.64739349764;3712.6658600726064;31032.224194395094;31127.84436077805;31946.361072778414;31953.360932781216;32125.1968700782;5402.135658294694;5410.3507579090765;5433.1850222466555;5448.291722076145;5484.890302193974;190217;192300;203948;204829;216114;48012.279815802794;48225.27662085072;48312.27531587029;48330.03339933252;48509.27236091461;50847.49152508502;50862;50917.236241456405;51008.744956275725;51060.744696276284;13163.341832908316;13304.933475332755;13329.466821327233;13347.599572012856;13458.730825383633;11215.887841121648;11286.20996530235;11328.376939268255;11329.37688427128;11366.659000230007;14137.515560866166;14157.150570965776;14166.716665666836;14202.715945681028;14342.784858227134;5568.331800184003;5657.688827114466;5679.176519404663;5730.455606717355;5824.223611798442;1101.427257825926;1101.5593762495066;1105.9889401105886;1106.3251416635846;1114.570890207276;24709.87645061763;24821.255362339158;25185;25292.747072529408;25318.11386601497;1198.4127777389017;1198.6703656494533;1200.903927685783;1203.4825025239056;1216.4708351866966;17482;17545.912270438566;17586.296548138187;17633.294668213126;17688.292468301122;263832;265530;267052;267070;267184;58843.705781471675;58921.41078589245;58982.41017589769;58998.705006474695;59438.108428373605;1449.1160392160984;1458.4020551573803;1506.7438535449;1508.057464084947;1510.2675202526766;29678;29682;29699;30462;30696.693033069383;39502.2099558013;39523.2095358097;39641.20717585574;39813.800930996316;39977.60022399739;240963;241498;242106;242120;242379;34761.82619086889;37328.25343493061;39320.60679393169;39719.60280397159;39752.403713943735;47791.522084780096;48998.26502602535;49110.26334604912;49237.50762492473;49425.75287123541;35119;35128.82435587806;35140.82429587836;35620.28759424849;35647.82176089103;35096.47355289622;35259.29481410409;35407.64592354043;35419.29161416809;35852.82073589615;41184;41559.7922010388;41600;41768.791156044026;41987.1602567953;34196.48705269373;34349.65650343464;34479.13802154966;34688.479672804424;34952.300953981285;26368;26477.470450590496;26803.73196268012;26861.194164175107;26899.596506052818 16:34:22 INFO - PID 12627 | |2;jquery.html;581.9039858423363;588.8086371929124;590.7371219807185;602.9638221706697;620.7641096383373;22157.667634985468;22855.771442285593;22892.427689307777;23026;23044.539109217807;26505.469890602177;26806;26897.865510672418;26898.59652105215;26901.730982690166;6511.706973186207;6632.137822083122;6639.93360066398;6640.269570347258;6641.269460359356;6572.737090516373;6590.703418346186;6759.425448836855;6772.72909083636;6789.355011273921;6734.8316292092595;6743.93256067438;6746.392824645781;6747.797566073001;6749.527533072687;6718.731250749964;6725.93274067258;6730.125083739107;6747.527673062863;6750.56121352113;6297.96851015742;6301.275353334379;6301.52738544611;6307.337729538424;6324.7470101195895;29989.400211995857;30586.69413305861;30651.693483065108;30698;31013.379732405225;2202.8898555072183;2213.6347502662043;2223.8332125090683;2228.732552093743;2231.944201394962;19248.133833977503;19585.216591336473;19601.21595136207;19649.115789789495;19657.901710491355;2407.723111842143;2420.6006009008493;2446.204983380391;2473.480569080495;2484.8012159027235;150352;153147;154013.22993385073;154417;154468;16054.27755750994;16369.139786020418;16797;16910.408135715435;17064.829351706325;25760.613590796154;26349.604755928678;26403;26433.73566264351;26504;10099.545520451597;10134.290599658101;10217.33587316822;10340.896591034143;10406.375617462976;8941.150590693873;8998.235150012322;9117.726468205965;9141.908580914105;9173.220276276457;9973.351732137533;10037.197024238043;10120.898791012143;10212.183025357954;10339;2824.7316504932;2869.4834929712433;2951.911442656724;2990.7607391408637;3001.2496875781053;999.9850002249826;1013.8023085498257;1015.9136473399844;1016.4104819204844;1034.4672493665769;22215.010060964865;22509.212177573707;22808.885955570116;22821.543569128855;22945.655815162787;1101.9008289254007;1105.4693747001484;1106.1261603333437;1107.3521989636154;1109.367660433545;12122.939385303018;12525.874741252654;12564.183328083645;12639.87360126387;12639.93680031594;605812;635851;641630;652292;657005;130577.34711326382;133661;134924;136245;137137;1077.9920774076263;1241.6088931986396;1245.894099001577;1253.3294687342227;1256.7989121740661;22939.65590516177;23522.64716029227;23711.288661340186;23778.16776412817;23841.52316953686;65216.347836522924;65394;65421;66750;68382.65808670924;88919.55540222257;92741;93478;94783;95111.52444237734;15949.043057416593;16292.266847992107;16345.754813677566;16376;16384.180790960167;25410.618840717772;25573.74426255788;25610.231693049238;25615.359616009748;25848.224553263433;20222.696659550413;20486.702885331466;20689.482762931046;20737.79262207419;21003.369898903056;16992.915035424743;17318.393856215476;18246.361377351728;18379.81620183781;19021;21234;21345.67981480248;21494.14023439045;21703.34889953304;21920.451988699773;15621.609459763597;15956.122413266918;16056.197190140212;16124.919375403517;16314.18429078565;20348.389548313575;20553.89723051375;20705;20771;20833.791662083186 16:34:22 INFO - PID 12627 | |3;mootools.html;1464.2532308522652;1521.9819676055215;1522.2921341576164;1533.2180587900175;1537.7847101405805;16381.672366552677;16941.407050753227;16956.4065257716;17024.91487542562;17269.222884970175;17002.064886431242;17199.140042997864;17244.361785630375;17274.2226599803;17339.653206935855;2743.4513097380504;2781.666200055986;2785.8885644574193;2788.6793018802896;2791.2184588315304;2730.931726706828;2813.549832026876;2817.7182281771866;2830.6461692288462;2839.1340641104457;2769.806113572051;2778.9444211115765;2786.9721302786916;2789.1771927281397;2791.1207969489647;2640.6435131257226;2709.295583148376;2711.30861630284;2713.4030513287044;2717.144099608617;2645.8280211786364;2648.0864101884818;2659.6941351744604;2665.506881226979;2687.9596806047925;15829.762553561706;16090.195490225558;16109.436169734121;16203.756943645854;16314.918425407915;8213;8500.44747091437;8516.318694504425;8532.23209911111;8547.102554231778;5705.258316418881;5959.582829201958;6104.236970378703;6114.724837382329;6127.264728232641;2081.916723331065;2121.331780489141;2129.7976692214215;2177.713400929018;2184.2901057156487;28904.71095289041;29192.708072919213;29277.12168634923;29296.70703292961;30581.38837223243;7319.670614822346;7331.596762178131;7351.301626345488;7477.925220747723;7540.660670269851;19362;19390.4182874514;19420.126094325507;19453.416397507815;19495.707564386546;8106.47307924983;8347.791305217419;8355.958220208982;8360;8363.958180209182;6442.871142577216;6662.56693314933;6849;7702.653380597886;7757.185495523002;5512.3385193777;5647.717614119278;5651.660900345993;5688.23208866806;5698.544116470673;1581.3279356273538;1583.8812089093365;1585.603599100225;1588.682263547272;1597.7936657823268;961.6345788600287;972.7179118055684;978.6738968697455;997.1623835977814;999.3304485994402;8274.29668478174;8301.916980830236;8303.335733141334;8306.584670766559;10077.294589378673;1047.7642530430744;1059.7350662334416;1069.7004838645191;1070.1118072000222;1086.603389762731;5514.393416724157;5539.41836107211;5555.944440555623;5721.685307308138;5736.311642602915;23669.171578994654;24058.63912041321;24539.3865153369;24722.13472528453;24760.628590571156;23629.172978945997;24408;24913.252602421595;25062.74937250604;25075.37311567189;12443.502259909683;12505.812412813815;12834.67913302175;12858.292793896238;13114.213147211007;9632.22942164611;9697.951510242403;9791.559379828068;10046.69859904204;10157;20572.868492233072;20814.37556873296;20872;20944.266950657264;21250.89374553117;21291.36125916225;21709.131634734433;21799.237026703988;21874.67187992213;21963.341099767033;13537.79693304621;13641.113327633671;13728.725425491633;13921.4431422746;13953.930230348784;15393.230338483256;15479.303431345841;15582.220888955733;15613.609659758142;15630.140342280829;12448.688782780502;12466;12509;12815.807762883751;12879.291638959947;10867.945660271647;11027.503762330554;11093;11142.665720028412;11221.438928053401;11110.722231944266;11199.328040317607;11259.437028148395;11269.154813388865;11332.263402878787;7654.2345765423015;7873.291403773459;7880.645370958206;7887.171846955987;7891.526508409514;8190.099089100075;8224.83550329002;8326.375521836007;8376.916230837858;8438.029626593021 16:34:22 INFO - PID 12627 | |4;prototype.html;529.507557971087;543.5760107116449;564.6978866306526;565.8330792416236;576.6914700635158;6725.730970761176;6766.7181417012835;6787.7624283150035;6793.694283757237;6831.2485626581165;6430.389113034266;6480.384363485473;6557.83605409865;6635.170603674541;6653.467722582194;5213.452587478317;5246.5278124968745;5255.369355677324;5281.366236051679;5335.466453354672;5055.368078990126;5226.7717086484645;5234.18870075139;5238.161894096946;5299.735013249341;5162.974185129069;5164.354455693038;5170.2503137045105;5205.86985325368;5218.582513398938;5157.2779810826705;5195.142801437758;5221.634485586011;5249.186376111708;5285.127953887604;4712.340272361872;4726.2201736713405;4726.409198850144;4749.715017098985;4755.167845627001;6503.967480162616;6505;6555.737770489175;6558.606483610999;6615.50383721218;6182.341593114958;6224.221972253423;6242.937570624281;6246.687665616746;6287.151234583318;6953.096097507342;7048.330408611174;7093.326134017312;7122.2521635228595;7130.144382674061;5263.578913686896;5299.906536917896;5342.27879236302;5347.491988261109;5364.302640656729;7396.260373962615;7400.740974065882;7415.925840741577;7458.179600243969;7496.8125796854965;5418.945810541844;5431.375391829911;5437.483439073281;5462.0441422751655;5516;5464.7814087436855;5487.176923461513;5501.817109321435;5560.221568980346;5564.4991950724625;6144.139820425144;6193.907091393633;6196.628202307876;6202.131701561785;6227.595206311576;7060.92939070613;7091.219965803757;7097.503174777716;7130.786076417612;7162.606056666829;1814.6642871068896;1826.506843152368;1886.302068234762;1895.649304878602;1930.7739585363395;1300.219868079164;1306.9285182985075;1326.4893016188837;1335.2255691698792;1353.1339942436848;6113;6134.263888333339;6176.81469555914;6216.968915155395;6250.499960003189;7013.473989450709;7066.4807066479925;7072.292770722886;7185.568865868065;7296;6096.451319381188;6213.689315534205;6221.346758590374;6268;6276.278228003838;3718.4236443351324;3786.2427514497213;3831.6397678823614;3835.4438606402473;3879.476270703475;7062.929370706227;7096.964515177494;7101.431885449152;7110.3600675939415;7145.214026457085;7446.7021319146625;7464.290892365217;7504.287092726182;7510.248975102555;7512.21121782205;7019.824504387431;7030.3321184487395;7049.29507049291;7073.418202356206;7103.822404439827;7314.378277846443;7314.597697126709;7344.375728062961;7361.447891408059;7394.8890766639615;7219.422446204396;7248.492605517563;7285.7814265572115;7349.77950661481;7379.594122323324;6980.162380514371;7075.5047146699235;7095.361417472453;7103.289671032855;7138.535995160298;5415.783368665367;5486.094794359045;5487.725613719218;5505.421930697219;5534.03154447962;7009.1589009319205;7161.355478007004;7215.629030484191;7249.311315425026;7296.963515182602;6765.391114799691;6834.521583489108;6890.758823441155;6910.896336554855;7021.332973367522;6858.931410685829;6891.620960847202;6913.340798208495;6919.619420931897;7014;6805.659717014229;6836.28219036994;6848.911082120001;6922.757703480354;6935.479839011993;6948.861022779415;6985.615791131537;6991.580505169706;7013.684384202621;7043.2604576518725;6740.022696708852;6785;6834.214065382345;6839.487038472234;6861.176658800776 16:34:22 INFO - PID 12627 | |5;yui.html;962.3552220012591;984.0897170117639;1000.5397517142114;1008.0826447932374;1033.2199189611842;18548.258069677213;18969.430917072492;19029.429117126474;19054.618907621858;21966.231181908635;19798.70301945472;20372.083256253467;20534.17863285467;20539.28112516062;20636.277730279442;987;1024.2113572549133;1030.4538594544904;1031.2471895516278;1034.0693375961657;1047.5024363427349;1074.6722249713835;1102.6747109602647;1103.0568863621609;1103.5971870267374;1160.22265082395;1166.422620802704;1169.8713141554422;1170.29197335612;1170.836082948388;1158.160333758023;1159.269660114131;1160.1183100843336;1160.4777849967547;1162.1167912386563;853.8676505141713;854.179987212277;856.2678909532323;857.2499063319596;857.2713193785232;19021.334253301207;19562.119704613327;19975.50061248466;20098.69851952222;20192.596148076955;22213.111475540958;22397.785306669142;22582.20962266313;22779.08883644652;22920.31239062814;18391.72412413815;18482;18500.444986650422;18573.257069717194;18790.060496975235;4013.9397909031386;4019.5578486366476;4033.798310084484;4035.858744943913;4038.0510580013674;148440;148572.99999999892;149577;150057;151818;15952.92023539875;16017.839821601869;16199.838001620068;16206.513804585882;16368.754468682979;32855.83572082124;33586.83206583952;33689;33770.662293377245;33818.323633527194;8642.22220000203;8973.461592304482;9045.276377889884;9699.806003879883;9981.850272245922;4980.975095124501;5037.622178336639;5052.823151189691;5065.822696205615;5068.0370729562;5688.175214593861;5781.508571771446;5790.305163380337;5808.593398462067;5811.186433899257;1170.2334970594275;1202.9218100823418;1204.5482943896038;1206.3666575047994;1207.2153100484754;756.1644382956754;766.371575308253;773.307889438949;783.5494590610422;786.09991559665;13505.459781608626;13765.242911639756;13913.165210087225;14456.843452523983;15480.458183963507;776.8834674798825;779.746582360735;780.2860382749834;789.9723509677134;794.3089512124374;5977.372375900555;6258.280297765724;6395.808125756234;6451.838704032437;6493.51298652603;123797;125613;126204;127778;129521.35239323744;109377;111186;112895;113353;117787.41106294414;105126.47436762767;105235;105999;106109.46945265224;110236;14511.1293322401;14673;14816.925915370353;14904.25478726081;15026.774598381251;48882;49099.26351104665;49274.75362623164;50591.49408505868;50770.49229507805;65376;66254.33745662482;66416;66704.33295666981;68299.3170068293;18133.00268485246;18421.53946151357;20447.38657840267;20724.58550828945;20742.06660700242;20470.69293960562;20786.27248046311;20879.791202088392;20899.68650470214;21026.369208923756;20720.896395517924;20931.16275348969;21027.68458473155;21392.251271205434;21970.34088977333;17079.7438038432;17193.398231061852;19361.709574356115;19444.222231110598;19557.70663440078;19407.611847763248;19525.70711439358;19593.02034898278;19823.801761982195;21578.6763198549;14171.574852754435;15123;15246.31391587359;15342.079475231521;15386.076835389913;15408.84591154074;15737.842621573638;15801.525954221392;15827.604309892346;15950.282237299582 16:34:22 INFO - PID 12627 | __end_tp_report 16:34:22 INFO - PID 12627 | __start_cc_report 16:34:22 INFO - PID 12627 | _x_x_mozilla_cycle_collect,329 16:34:22 INFO - PID 12627 | __end_cc_report 16:34:22 INFO - PID 12627 | __startTimestamp1504481662867__endTimestamp 16:34:22 INFO - PID 12627 | 16:34:22 INFO - PID 12627 | ------- Summary: start ------- 16:34:22 INFO - PID 12627 | Number of tests: 6 16:34:22 INFO - PID 12627 | 16:34:22 INFO - PID 12627 | [#0] dojo.html Cycles:165 Average:6707.33 Median:6908.97 stddev:723.73 (10.5%) stddev-sans-first:690.68 16:34:22 INFO - PID 12627 | Values: 3862.4 3896.8 3904.3 3908.4 3934.5 6627.5 6737.9 6758.9 6776.5 6800.2 6647.6 6741.7 6743.8 6747.5 6752.1 5998.8 6003.2 6008.5 6040.8 6047.8 6058.4 6059.2 6059.9 6073.4 6076.8 6048.3 6082.5 6116.7 6139.0 6202.5 6060.7 6067.2 6069.6 6082.4 6160.2 5861.2 5940.9 5950.0 5961.1 6034.9 6701.4 6720.7 6744.4 6791.0 6810.0 7265.7 7280.2 7375.2 7385.1 7387.2 7229.7 7316.0 7341.6 7383.3 7445.0 6820.9 6849.9 6888.8 6906.9 6908.6 7480.4 7506.8 7507.4 7584.5 7588.1 7081.3 7166.1 7192.9 7238.4 7318.1 7301.3 7302.7 7303.9 7317.5 7320.0 7165.1 7228.9 7251.8 7258.0 7259.9 7274.8 7320.3 7321.3 7335.9 7365.1 6085.8 6163.0 6165.6 6174.1 6253.5 5329.7 5337.0 5397.4 5441.9 5443.7 6191.3 6292.9 6336.1 6354.0 6355.0 7217.2 7301.7 7304.7 7318.8 7334.6 6249.1 6263.8 6266.7 6335.6 6348.0 6500.3 6595.8 6615.6 6690.9 6707.5 6942.9 7021.8 7023.4 7025.9 7040.6 7166.6 7332.0 7350.1 7351.2 7352.2 7048.9 7060.4 7116.6 7139.8 7152.6 7082.8 7152.9 7166.8 7189.1 7275.1 7265.4 7265.5 7272.8 7284.2 7290.1 6896.7 6990.0 6990.7 7003.8 7005.6 6982.5 6983.4 6987.1 6994.9 7006.6 6884.4 6909.0 6985.3 7060.0 7080.9 7047.8 7049.5 7080.8 7130.5 7177.0 6715.9 6850.1 6852.5 6858.8 6859.3 16:34:22 INFO - PID 12627 | 16:34:22 INFO - PID 12627 | [#1] ext.html Cycles:180 Average:41365.23 Median:20520.46 stddev:61842.00 (301.4%) stddev-sans-first:61940.78 16:34:22 INFO - PID 12627 | Values: 881.4 929.3 961.7 987.7 991.9 41274.0 42024.6 42082.8 42111.6 42147.4 38746.8 39003.0 39018.6 39035.2 39038.8 7269.6 7410.8 7414.5 7417.7 7425.3 7178.6 7240.7 7245.0 7253.1 7253.2 7237.2 7870.3 7988.1 8016.9 8017.9 7914.6 7980.6 7983.7 7987.2 7989.3 7287.5 7660.2 7816.6 7825.5 7833.9 39175.8 39225.2 39287.0 39554.4 39659.2 3640.1 3708.3 3709.1 3711.6 3712.7 31032.2 31127.8 31946.4 31953.4 32125.2 5402.1 5410.4 5433.2 5448.3 5484.9 190217.0 192300.0 203948.0 204829.0 216114.0 48012.3 48225.3 48312.3 48330.0 48509.3 50847.5 50862.0 50917.2 51008.7 51060.7 13163.3 13304.9 13329.5 13347.6 13458.7 11215.9 11286.2 11328.4 11329.4 11366.7 14137.5 14157.2 14166.7 14202.7 14342.8 5568.3 5657.7 5679.2 5730.5 5824.2 1101.4 1101.6 1106.0 1106.3 1114.6 24709.9 24821.3 25185.0 25292.7 25318.1 1198.4 1198.7 1200.9 1203.5 1216.5 17482.0 17545.9 17586.3 17633.3 17688.3 263832.0 265530.0 267052.0 267070.0 267184.0 58843.7 58921.4 58982.4 58998.7 59438.1 1449.1 1458.4 1506.7 1508.1 1510.3 29678.0 29682.0 29699.0 30462.0 30696.7 39502.2 39523.2 39641.2 39813.8 39977.6 240963.0 241498.0 242106.0 242120.0 242379.0 34761.8 37328.3 39320.6 39719.6 39752.4 47791.5 48998.3 49110.3 49237.5 49425.8 35119.0 35128.8 35140.8 35620.3 35647.8 35096.5 35259.3 35407.6 35419.3 35852.8 41184.0 41559.8 41600.0 41768.8 41987.2 34196.5 34349.7 34479.1 34688.5 34952.3 26368.0 26477.5 26803.7 26861.2 26899.6 16:34:22 INFO - PID 12627 | 16:34:22 INFO - PID 12627 | [#2] jquery.html Cycles:180 Average:41658.28 Median:22942.66 stddev:106793.19 (465.5%) stddev-sans-first:107048.24 16:34:22 INFO - PID 12627 | Values: 581.9 588.8 590.7 603.0 620.8 22157.7 22855.8 22892.4 23026.0 23044.5 26505.5 26806.0 26897.9 26898.6 26901.7 6511.7 6632.1 6639.9 6640.3 6641.3 6572.7 6590.7 6759.4 6772.7 6789.4 6734.8 6743.9 6746.4 6747.8 6749.5 6718.7 6725.9 6730.1 6747.5 6750.6 6298.0 6301.3 6301.5 6307.3 6324.7 29989.4 30586.7 30651.7 30698.0 31013.4 2202.9 2213.6 2223.8 2228.7 2231.9 19248.1 19585.2 19601.2 19649.1 19657.9 2407.7 2420.6 2446.2 2473.5 2484.8 150352.0 153147.0 154013.2 154417.0 154468.0 16054.3 16369.1 16797.0 16910.4 17064.8 25760.6 26349.6 26403.0 26433.7 26504.0 10099.5 10134.3 10217.3 10340.9 10406.4 8941.2 8998.2 9117.7 9141.9 9173.2 9973.4 10037.2 10120.9 10212.2 10339.0 2824.7 2869.5 2951.9 2990.8 3001.2 1000.0 1013.8 1015.9 1016.4 1034.5 22215.0 22509.2 22808.9 22821.5 22945.7 1101.9 1105.5 1106.1 1107.4 1109.4 12122.9 12525.9 12564.2 12639.9 12639.9 605812.0 635851.0 641630.0 652292.0 657005.0 130577.3 133661.0 134924.0 136245.0 137137.0 1078.0 1241.6 1245.9 1253.3 1256.8 22939.7 23522.6 23711.3 23778.2 23841.5 65216.3 65394.0 65421.0 66750.0 68382.7 88919.6 92741.0 93478.0 94783.0 95111.5 15949.0 16292.3 16345.8 16376.0 16384.2 25410.6 25573.7 25610.2 25615.4 25848.2 20222.7 20486.7 20689.5 20737.8 21003.4 16992.9 17318.4 18246.4 18379.8 19021.0 21234.0 21345.7 21494.1 21703.3 21920.5 15621.6 15956.1 16056.2 16124.9 16314.2 20348.4 20553.9 20705.0 20771.0 20833.8 16:34:22 INFO - PID 12627 | 16:34:22 INFO - PID 12627 | [#3] mootools.html Cycles:180 Average:10329.71 Median:24988.00 stddev:7572.91 (30.3%) stddev-sans-first:7564.86 16:34:22 INFO - PID 12627 | Values: 1464.3 1522.0 1522.3 1533.2 1537.8 16381.7 16941.4 16956.4 17024.9 17269.2 17002.1 17199.1 17244.4 17274.2 17339.7 2743.5 2781.7 2785.9 2788.7 2791.2 2730.9 2813.5 2817.7 2830.6 2839.1 2769.8 2778.9 2787.0 2789.2 2791.1 2640.6 2709.3 2711.3 2713.4 2717.1 2645.8 2648.1 2659.7 2665.5 2688.0 15829.8 16090.2 16109.4 16203.8 16314.9 8213.0 8500.4 8516.3 8532.2 8547.1 5705.3 5959.6 6104.2 6114.7 6127.3 2081.9 2121.3 2129.8 2177.7 2184.3 28904.7 29192.7 29277.1 29296.7 30581.4 7319.7 7331.6 7351.3 7477.9 7540.7 19362.0 19390.4 19420.1 19453.4 19495.7 8106.5 8347.8 8356.0 8360.0 8364.0 6442.9 6662.6 6849.0 7702.7 7757.2 5512.3 5647.7 5651.7 5688.2 5698.5 1581.3 1583.9 1585.6 1588.7 1597.8 961.6 972.7 978.7 997.2 999.3 8274.3 8301.9 8303.3 8306.6 10077.3 1047.8 1059.7 1069.7 1070.1 1086.6 5514.4 5539.4 5555.9 5721.7 5736.3 23669.2 24058.6 24539.4 24722.1 24760.6 23629.2 24408.0 24913.3 25062.7 25075.4 12443.5 12505.8 12834.7 12858.3 13114.2 9632.2 9698.0 9791.6 10046.7 10157.0 20572.9 20814.4 20872.0 20944.3 21250.9 21291.4 21709.1 21799.2 21874.7 21963.3 13537.8 13641.1 13728.7 13921.4 13953.9 15393.2 15479.3 15582.2 15613.6 15630.1 12448.7 12466.0 12509.0 12815.8 12879.3 10867.9 11027.5 11093.0 11142.7 11221.4 11110.7 11199.3 11259.4 11269.2 11332.3 7654.2 7873.3 7880.6 7887.2 7891.5 8190.1 8224.8 8326.4 8376.9 8438.0 16:34:22 INFO - PID 12627 | 16:34:22 INFO - PID 12627 | [#4] prototype.html Cycles:180 Average:5928.75 Median:6558.22 stddev:1673.59 (25.5%) stddev-sans-first:1628.48 16:34:22 INFO - PID 12627 | Values: 529.5 543.6 564.7 565.8 576.7 6725.7 6766.7 6787.8 6793.7 6831.2 6430.4 6480.4 6557.8 6635.2 6653.5 5213.5 5246.5 5255.4 5281.4 5335.5 5055.4 5226.8 5234.2 5238.2 5299.7 5163.0 5164.4 5170.3 5205.9 5218.6 5157.3 5195.1 5221.6 5249.2 5285.1 4712.3 4726.2 4726.4 4749.7 4755.2 6504.0 6505.0 6555.7 6558.6 6615.5 6182.3 6224.2 6242.9 6246.7 6287.2 6953.1 7048.3 7093.3 7122.3 7130.1 5263.6 5299.9 5342.3 5347.5 5364.3 7396.3 7400.7 7415.9 7458.2 7496.8 5418.9 5431.4 5437.5 5462.0 5516.0 5464.8 5487.2 5501.8 5560.2 5564.5 6144.1 6193.9 6196.6 6202.1 6227.6 7060.9 7091.2 7097.5 7130.8 7162.6 1814.7 1826.5 1886.3 1895.6 1930.8 1300.2 1306.9 1326.5 1335.2 1353.1 6113.0 6134.3 6176.8 6217.0 6250.5 7013.5 7066.5 7072.3 7185.6 7296.0 6096.5 6213.7 6221.3 6268.0 6276.3 3718.4 3786.2 3831.6 3835.4 3879.5 7062.9 7097.0 7101.4 7110.4 7145.2 7446.7 7464.3 7504.3 7510.2 7512.2 7019.8 7030.3 7049.3 7073.4 7103.8 7314.4 7314.6 7344.4 7361.4 7394.9 7219.4 7248.5 7285.8 7349.8 7379.6 6980.2 7075.5 7095.4 7103.3 7138.5 5415.8 5486.1 5487.7 5505.4 5534.0 7009.2 7161.4 7215.6 7249.3 7297.0 6765.4 6834.5 6890.8 6910.9 7021.3 6858.9 6891.6 6913.3 6919.6 7014.0 6805.7 6836.3 6848.9 6922.8 6935.5 6948.9 6985.6 6991.6 7013.7 7043.3 6740.0 6785.0 6834.2 6839.5 6861.2 16:34:22 INFO - PID 12627 | 16:34:22 INFO - PID 12627 | [#5] yui.html Cycles:180 Average:26723.89 Median:19559.91 stddev:37484.82 (191.6%) stddev-sans-first:37540.06 16:34:22 INFO - PID 12627 | Values: 962.4 984.1 1000.5 1008.1 1033.2 18548.3 18969.4 19029.4 19054.6 21966.2 19798.7 20372.1 20534.2 20539.3 20636.3 987.0 1024.2 1030.5 1031.2 1034.1 1047.5 1074.7 1102.7 1103.1 1103.6 1160.2 1166.4 1169.9 1170.3 1170.8 1158.2 1159.3 1160.1 1160.5 1162.1 853.9 854.2 856.3 857.2 857.3 19021.3 19562.1 19975.5 20098.7 20192.6 22213.1 22397.8 22582.2 22779.1 22920.3 18391.7 18482.0 18500.4 18573.3 18790.1 4013.9 4019.6 4033.8 4035.9 4038.1 148440.0 148573.0 149577.0 150057.0 151818.0 15952.9 16017.8 16199.8 16206.5 16368.8 32855.8 33586.8 33689.0 33770.7 33818.3 8642.2 8973.5 9045.3 9699.8 9981.9 4981.0 5037.6 5052.8 5065.8 5068.0 5688.2 5781.5 5790.3 5808.6 5811.2 1170.2 1202.9 1204.5 1206.4 1207.2 756.2 766.4 773.3 783.5 786.1 13505.5 13765.2 13913.2 14456.8 15480.5 776.9 779.7 780.3 790.0 794.3 5977.4 6258.3 6395.8 6451.8 6493.5 123797.0 125613.0 126204.0 127778.0 129521.4 109377.0 111186.0 112895.0 113353.0 117787.4 105126.5 105235.0 105999.0 106109.5 110236.0 14511.1 14673.0 14816.9 14904.3 15026.8 48882.0 49099.3 49274.8 50591.5 50770.5 65376.0 66254.3 66416.0 66704.3 68299.3 18133.0 18421.5 20447.4 20724.6 20742.1 20470.7 20786.3 20879.8 20899.7 21026.4 20720.9 20931.2 21027.7 21392.3 21970.3 17079.7 17193.4 19361.7 19444.2 19557.7 19407.6 19525.7 19593.0 19823.8 21578.7 14171.6 15123.0 15246.3 15342.1 15386.1 15408.8 15737.8 15801.5 15827.6 15950.3 16:34:22 INFO - PID 12627 | -------- Summary: end -------- 16:34:22 INFO - PID 12627 | 16:34:23 INFO - TEST-INFO | 12627: exit 0 16:34:23 INFO - TEST-OK | dromaeo_css | took 1110153ms 16:34:23 INFO - TEST-START | kraken 16:34:23 INFO - Initialising browser for kraken test... 16:34:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47750/getInfo.html -profile /tmp/tmpudUy1q/profile 16:34:23 INFO - TEST-INFO | started process 12999 (/builds/slave/test/build/application/firefox/firefox http://localhost:47750/getInfo.html) 16:34:28 INFO - TEST-INFO | 12999: exit 0 16:34:28 INFO - Browser initialized. 16:34:28 INFO - Running cycle 1/1 for kraken test... 16:34:28 INFO - Using env: {'DISPLAY': ':0', 16:34:28 INFO - 'HOME': '/home/cltbld', 16:34:28 INFO - 'JSGC_DISABLE_POISONING': '1', 16:34:28 INFO - 'LANG': 'en_US.UTF-8', 16:34:28 INFO - 'LANGUAGE': 'en_US:en', 16:34:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:34:28 INFO - 'LOGNAME': 'cltbld', 16:34:28 INFO - 'MAIL': '/var/mail/cltbld', 16:34:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:34:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:34:29 INFO - 'MOZ_CRASHREPORTER': '1', 16:34:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:34:29 INFO - 'MOZ_NO_REMOTE': '1', 16:34:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:34:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:34:29 INFO - 'NO_EM_RESTART': '1', 16:34:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:34:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:29 INFO - 'PWD': '/builds/slave/test', 16:34:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:34:29 INFO - 'RUST_BACKTRACE': 'full', 16:34:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:34:29 INFO - 'SHELL': '/bin/bash', 16:34:29 INFO - 'SHLVL': '1', 16:34:29 INFO - 'TERM': 'linux', 16:34:29 INFO - 'TMOUT': '86400', 16:34:29 INFO - 'USER': 'cltbld', 16:34:29 INFO - 'XDG_SESSION_COOKIE': 'dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718', 16:34:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:29 INFO - '_': '/tools/buildbot/bin/python'} 16:34:29 INFO - TEST-INFO | started process 13186 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpudUy1q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:34:29 INFO - PID 13186 | 16:34:29 INFO - PID 13186 | (/builds/slave/test/build/application/firefox/firefox:13239): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:34:29 INFO - PID 13186 | 16:34:40 INFO - PID 13186 | RSS: Main: 163012608 16:34:40 INFO - PID 13186 | 16:34:42 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47750/tests/kraken/driver.html?testName=audio-beat-detection) 16:34:43 INFO - PID 13186 | RSS: Main: 167575552 16:34:43 INFO - PID 13186 | 16:34:46 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47750/tests/kraken/driver.html?testName=audio-dft) 16:34:46 INFO - PID 13186 | RSS: Main: 171868160 16:34:46 INFO - PID 13186 | 16:34:49 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47750/tests/kraken/driver.html?testName=audio-fft) 16:34:50 INFO - PID 13186 | RSS: Main: 165494784 16:34:50 INFO - PID 13186 | 16:34:52 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47750/tests/kraken/driver.html?testName=audio-oscillator) 16:34:53 INFO - PID 13186 | RSS: Main: 166584320 16:34:53 INFO - PID 13186 | 16:34:55 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47750/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:34:55 INFO - PID 13186 | RSS: Main: 166158336 16:34:55 INFO - PID 13186 | 16:34:58 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47750/tests/kraken/driver.html?testName=imaging-darkroom) 16:34:59 INFO - PID 13186 | RSS: Main: 166334464 16:34:59 INFO - PID 13186 | 16:35:02 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47750/tests/kraken/driver.html?testName=imaging-desaturate) 16:35:03 INFO - PID 13186 | RSS: Main: 165863424 16:35:03 INFO - PID 13186 | 16:35:06 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47750/tests/kraken/driver.html?testName=json-parse-financial) 16:35:06 INFO - PID 13186 | RSS: Main: 167596032 16:35:06 INFO - PID 13186 | 16:35:08 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47750/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:35:08 INFO - PID 13186 | RSS: Main: 167702528 16:35:08 INFO - PID 13186 | 16:35:10 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47750/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:35:11 INFO - PID 13186 | RSS: Main: 167731200 16:35:11 INFO - PID 13186 | 16:35:13 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47750/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:35:13 INFO - PID 13186 | RSS: Main: 164499456 16:35:13 INFO - PID 13186 | 16:35:16 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47750/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:35:16 INFO - PID 13186 | RSS: Main: 163905536 16:35:16 INFO - PID 13186 | 16:35:19 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47750/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:35:20 INFO - PID 13186 | RSS: Main: 164532224 16:35:20 INFO - PID 13186 | 16:35:21 INFO - PID 13186 | Cycle 1(1): loaded http://localhost:47750/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47750/tests/kraken/driver.html?testName=ai-astar) 16:35:22 INFO - PID 13186 | RSS: Main: 166076416 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | __start_tp_report 16:35:22 INFO - PID 13186 | _x_x_mozilla_page_load 16:35:22 INFO - PID 13186 | _x_x_mozilla_page_load_details 16:35:22 INFO - PID 13186 | |i|pagename|runs| 16:35:22 INFO - PID 13186 | |0;ai-astar;99;107;99;105;105;105;107;99;105;106 16:35:22 INFO - PID 13186 | |1;audio-beat-detection;138;121;149;181;117;137;149;111;161;112 16:35:22 INFO - PID 13186 | |2;audio-dft;161;161;161;160;163;163;161;162;166;170 16:35:22 INFO - PID 13186 | |3;audio-fft;73;74;73;139;74;72;72;100;96;73 16:35:22 INFO - PID 13186 | |4;audio-oscillator;78;82;125;81;77;78;130;78;78;122 16:35:22 INFO - PID 13186 | |5;imaging-gaussian-blur;113;114;113;114;114;114;114;115;113;114 16:35:22 INFO - PID 13186 | |6;imaging-darkroom;152;153;157;153;152;153;152;153;153;154 16:35:22 INFO - PID 13186 | |7;imaging-desaturate;94;95;94;94;93;94;94;93;94;94 16:35:22 INFO - PID 13186 | |8;json-parse-financial;44;43;43;44;44;47;49;44;49;44 16:35:22 INFO - PID 13186 | |9;json-stringify-tinderbox;37;36;37;37;37;36;37;37;36;37 16:35:22 INFO - PID 13186 | |10;stanford-crypto-aes;74;72;74;81;74;72;73;72;71;72 16:35:22 INFO - PID 13186 | |11;stanford-crypto-ccm;113;116;115;111;116;111;119;112;111;153 16:35:22 INFO - PID 13186 | |12;stanford-crypto-pbkdf2;169;161;155;154;153;156;157;162;158;156 16:35:22 INFO - PID 13186 | |13;stanford-crypto-sha256-iterative;60;62;66;60;60;59;59;60;59;57 16:35:22 INFO - PID 13186 | __end_tp_report 16:35:22 INFO - PID 13186 | __start_cc_report 16:35:22 INFO - PID 13186 | _x_x_mozilla_cycle_collect,895 16:35:22 INFO - PID 13186 | __end_cc_report 16:35:22 INFO - PID 13186 | __startTimestamp1504481722200__endTimestamp 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | ------- Summary: start ------- 16:35:22 INFO - PID 13186 | Number of tests: 14 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#0] ai-astar Cycles:10 Average:103.70 Median:107.00 stddev:3.33 (3.1%) stddev-sans-first:3.07 16:35:22 INFO - PID 13186 | Values: 99.0 107.0 99.0 105.0 105.0 105.0 107.0 99.0 105.0 106.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:143.50 stddev:22.98 (16.0%) stddev-sans-first:24.38 16:35:22 INFO - PID 13186 | Values: 138.0 121.0 149.0 181.0 117.0 137.0 149.0 111.0 161.0 112.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#2] audio-dft Cycles:10 Average:162.80 Median:162.50 stddev:3.05 (1.9%) stddev-sans-first:3.16 16:35:22 INFO - PID 13186 | Values: 161.0 161.0 161.0 160.0 163.0 163.0 161.0 162.0 166.0 170.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#3] audio-fft Cycles:10 Average:84.60 Median:73.00 stddev:21.79 (29.8%) stddev-sans-first:22.70 16:35:22 INFO - PID 13186 | Values: 73.0 74.0 73.0 139.0 74.0 72.0 72.0 100.0 96.0 73.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:78.00 stddev:22.74 (29.2%) stddev-sans-first:23.47 16:35:22 INFO - PID 13186 | Values: 78.0 82.0 125.0 81.0 77.0 78.0 130.0 78.0 78.0 122.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.60 16:35:22 INFO - PID 13186 | Values: 113.0 114.0 113.0 114.0 114.0 114.0 114.0 115.0 113.0 114.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#6] imaging-darkroom Cycles:10 Average:153.20 Median:153.00 stddev:1.48 (1.0%) stddev-sans-first:1.50 16:35:22 INFO - PID 13186 | Values: 152.0 153.0 157.0 153.0 152.0 153.0 152.0 153.0 153.0 154.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:35:22 INFO - PID 13186 | Values: 94.0 95.0 94.0 94.0 93.0 94.0 94.0 93.0 94.0 94.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#8] json-parse-financial Cycles:10 Average:45.10 Median:44.00 stddev:2.33 (5.3%) stddev-sans-first:2.44 16:35:22 INFO - PID 13186 | Values: 44.0 43.0 43.0 44.0 44.0 47.0 49.0 44.0 49.0 44.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#9] json-stringify-tinderbox Cycles:10 Average:36.70 Median:37.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 16:35:22 INFO - PID 13186 | Values: 37.0 36.0 37.0 37.0 37.0 36.0 37.0 37.0 36.0 37.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#10] stanford-crypto-aes Cycles:10 Average:73.50 Median:73.50 stddev:2.84 (3.9%) stddev-sans-first:3.00 16:35:22 INFO - PID 13186 | Values: 74.0 72.0 74.0 81.0 74.0 72.0 73.0 72.0 71.0 72.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#11] stanford-crypto-ccm Cycles:10 Average:117.70 Median:115.50 stddev:12.69 (11.0%) stddev-sans-first:13.35 16:35:22 INFO - PID 13186 | Values: 113.0 116.0 115.0 111.0 116.0 111.0 119.0 112.0 111.0 153.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.10 Median:157.50 stddev:4.77 (3.0%) stddev-sans-first:3.02 16:35:22 INFO - PID 13186 | Values: 169.0 161.0 155.0 154.0 153.0 156.0 157.0 162.0 158.0 156.0 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.20 Median:60.00 stddev:2.39 (4.0%) stddev-sans-first:2.54 16:35:22 INFO - PID 13186 | Values: 60.0 62.0 66.0 60.0 60.0 59.0 59.0 60.0 59.0 57.0 16:35:22 INFO - PID 13186 | -------- Summary: end -------- 16:35:22 INFO - PID 13186 | 16:35:22 INFO - PID 13186 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:35:22 INFO - TEST-INFO | 13186: exit 0 16:35:22 INFO - TEST-OK | kraken | took 59418ms 16:35:22 INFO - SUITE-END | took 1169s 16:35:23 INFO - Completed test suite (00:19:30) 16:35:23 INFO - javascript benchmark 16:35:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9258.949536900403, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3862.439946207798, 3896.76619402836, 3904.3362628353184, 3908.3941988991705, 3934.468846705696, 6627.4698024158015, 6737.865242695143, 6758.932410675891, 6776.491763117761, 6800.217974932884, 6647.601143931368, 6741.730330786762, 6743.763968261112, 6747.460203183746, 6752.088468056811, 5998.790042348519, 6003.219581454405, 6008.519318454513, 6040.788572399967, 6047.848803779897, 6058.363871793443, 6059.212302400681, 6059.909101363461, 6073.362296958822, 6076.848078798043, 6048.274207095133, 6082.543809214305, 6116.694165291718, 6138.969305153468, 6202.534809889254, 6060.696965151769, 6067.150598916155, 6069.635821850659, 6082.361352058058, 6160.1991741073825, 5861.238039054938, 5940.910886336708, 5949.970250148722, 5961.135635332896, 6034.879302413927, 6701.396874281338, 6720.6639668016405, 6744.359285867835, 6791.0, 6810.0, 7265.745698900566, 7280.162781280114, 7375.151857536344, 7385.113786345619, 7387.150477695081, 7229.710811567531, 7316.0, 7341.559506429578, 7383.2985866342615, 7444.9627751861435, 6820.897686534706, 6849.897251541181, 6888.758893438706, 6906.861862762717, 6908.585484870924, 7480.438967077382, 7506.774796756106, 7507.436942229355, 7584.54492730438, 7588.089429268523, 7081.256468070881, 7166.068411106523, 7192.856142877114, 7238.420926325882, 7318.085239345082, 7301.306375894282, 7302.707891684379, 7303.89044164338, 7317.524360916524, 7320.011798407147, 7165.104361954755, 7228.927710722825, 7251.782446526613, 7258.02016727735, 7259.89110163348, 7274.8181295466975, 7320.304571065741, 7321.304476074764, 7335.88996165058, 7365.116186057708, 6085.817425477243, 6163.044728067067, 6165.568410211242, 6174.104754810527, 6253.499720022478, 5329.7068661224, 5336.973315133399, 5397.379301380392, 5441.945580544223, 5443.700596467153, 6191.318954914956, 6292.905606415908, 6336.112944187817, 6354.0, 6355.0, 7217.170025447036, 7301.671424785791, 7304.671289791972, 7318.780436586911, 7334.596597187206, 6249.0938813871735, 6263.780767673201, 6266.717997690115, 6335.556511044275, 6348.016057511093, 6500.252470965805, 6595.8021259362295, 6615.6030638161865, 6690.899636505456, 6707.463402927754, 6942.895856562156, 7021.754238601625, 7023.403010744144, 7025.894611580728, 7040.6479676016, 7166.570005799668, 7332.0, 7350.117985841734, 7351.228121047427, 7352.191258961617, 7048.859022819617, 7060.399866011447, 7116.644167791694, 7139.750108746169, 7152.570845749272, 7082.822929426806, 7152.892706609509, 7166.820829479097, 7189.137303523401, 7275.126984761862, 7265.382442492236, 7265.4550908681, 7272.818179545554, 7284.162321333115, 7290.088738907637, 6896.655167241718, 6990.0, 6990.720371185095, 7003.7548685795755, 7005.579665220103, 6982.511224214256, 6983.406410455168, 6987.091678081817, 6994.895076573957, 7006.579605223703, 6884.449244060564, 6908.965455172692, 6985.26654701249, 7060.0, 7080.893786593102, 7047.788566343018, 7049.471289653194, 7080.787576372717, 7130.5365151265005, 7177.0, 6715.932840671726, 6850.109485766819, 6852.520323577502, 6858.828529286808, 6859.348361905611], "value": 6661.086966689793, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [881.3830318776854, 929.284450972751, 961.7307153996882, 987.7135630667107, 991.861139440478, 41274.0, 42024.57975420249, 42082.78958605203, 42111.57888421115, 42147.367789483185, 38746.80626596863, 39003.0, 39018.60981390192, 39035.219295614144, 39038.80480597593, 7269.600171990552, 7410.777676669709, 7414.518056326321, 7417.740379086733, 7425.257474252586, 7178.5692858428665, 7240.710371585156, 7244.963775181117, 7253.057102576658, 7253.238409966957, 7237.167725711558, 7870.2916737493615, 7988.121306656263, 8016.919830801705, 8017.919820801805, 7914.643841027168, 7980.640871160841, 7983.680652773882, 7987.161348058457, 7989.2809647132035, 7287.489875708702, 7660.195679453633, 7816.570088645122, 7825.452218344718, 7833.882491762628, 39175.8041209795, 39225.21549569021, 39287.0, 39554.40668389977, 39659.20681586352, 3640.0899775056237, 3708.332500149972, 3709.146896213859, 3711.64739349764, 3712.6658600726064, 31032.224194395094, 31127.84436077805, 31946.361072778414, 31953.360932781216, 32125.1968700782, 5402.135658294694, 5410.3507579090765, 5433.1850222466555, 5448.291722076145, 5484.890302193974, 190217.0, 192300.0, 203948.0, 204829.0, 216114.0, 48012.279815802794, 48225.27662085072, 48312.27531587029, 48330.03339933252, 48509.27236091461, 50847.49152508502, 50862.0, 50917.236241456405, 51008.744956275725, 51060.744696276284, 13163.341832908316, 13304.933475332755, 13329.466821327233, 13347.599572012856, 13458.730825383633, 11215.887841121648, 11286.20996530235, 11328.376939268255, 11329.37688427128, 11366.659000230007, 14137.515560866166, 14157.150570965776, 14166.716665666836, 14202.715945681028, 14342.784858227134, 5568.331800184003, 5657.688827114466, 5679.176519404663, 5730.455606717355, 5824.223611798442, 1101.427257825926, 1101.5593762495066, 1105.9889401105886, 1106.3251416635846, 1114.570890207276, 24709.87645061763, 24821.255362339158, 25185.0, 25292.747072529408, 25318.11386601497, 1198.4127777389017, 1198.6703656494533, 1200.903927685783, 1203.4825025239056, 1216.4708351866966, 17482.0, 17545.912270438566, 17586.296548138187, 17633.294668213126, 17688.292468301122, 263832.0, 265530.0, 267052.0, 267070.0, 267184.0, 58843.705781471675, 58921.41078589245, 58982.41017589769, 58998.705006474695, 59438.108428373605, 1449.1160392160984, 1458.4020551573803, 1506.7438535449, 1508.057464084947, 1510.2675202526766, 29678.0, 29682.0, 29699.0, 30462.0, 30696.693033069383, 39502.2099558013, 39523.2095358097, 39641.20717585574, 39813.800930996316, 39977.60022399739, 240963.0, 241498.0, 242106.0, 242120.0, 242379.0, 34761.82619086889, 37328.25343493061, 39320.60679393169, 39719.60280397159, 39752.403713943735, 47791.522084780096, 48998.26502602535, 49110.26334604912, 49237.50762492473, 49425.75287123541, 35119.0, 35128.82435587806, 35140.82429587836, 35620.28759424849, 35647.82176089103, 35096.47355289622, 35259.29481410409, 35407.64592354043, 35419.29161416809, 35852.82073589615, 41184.0, 41559.7922010388, 41600.0, 41768.791156044026, 41987.1602567953, 34196.48705269373, 34349.65650343464, 34479.13802154966, 34688.479672804424, 34952.300953981285, 26368.0, 26477.470450590496, 26803.73196268012, 26861.194164175107, 26899.596506052818], "value": 18161.22419701059, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.9039858423363, 588.8086371929124, 590.7371219807185, 602.9638221706697, 620.7641096383373, 22157.667634985468, 22855.771442285593, 22892.427689307777, 23026.0, 23044.539109217807, 26505.469890602177, 26806.0, 26897.865510672418, 26898.59652105215, 26901.730982690166, 6511.706973186207, 6632.137822083122, 6639.93360066398, 6640.269570347258, 6641.269460359356, 6572.737090516373, 6590.703418346186, 6759.425448836855, 6772.72909083636, 6789.355011273921, 6734.8316292092595, 6743.93256067438, 6746.392824645781, 6747.797566073001, 6749.527533072687, 6718.731250749964, 6725.93274067258, 6730.125083739107, 6747.527673062863, 6750.56121352113, 6297.96851015742, 6301.275353334379, 6301.52738544611, 6307.337729538424, 6324.7470101195895, 29989.400211995857, 30586.69413305861, 30651.693483065108, 30698.0, 31013.379732405225, 2202.8898555072183, 2213.6347502662043, 2223.8332125090683, 2228.732552093743, 2231.944201394962, 19248.133833977503, 19585.216591336473, 19601.21595136207, 19649.115789789495, 19657.901710491355, 2407.723111842143, 2420.6006009008493, 2446.204983380391, 2473.480569080495, 2484.8012159027235, 150352.0, 153147.0, 154013.22993385073, 154417.0, 154468.0, 16054.27755750994, 16369.139786020418, 16797.0, 16910.408135715435, 17064.829351706325, 25760.613590796154, 26349.604755928678, 26403.0, 26433.73566264351, 26504.0, 10099.545520451597, 10134.290599658101, 10217.33587316822, 10340.896591034143, 10406.375617462976, 8941.150590693873, 8998.235150012322, 9117.726468205965, 9141.908580914105, 9173.220276276457, 9973.351732137533, 10037.197024238043, 10120.898791012143, 10212.183025357954, 10339.0, 2824.7316504932, 2869.4834929712433, 2951.911442656724, 2990.7607391408637, 3001.2496875781053, 999.9850002249826, 1013.8023085498257, 1015.9136473399844, 1016.4104819204844, 1034.4672493665769, 22215.010060964865, 22509.212177573707, 22808.885955570116, 22821.543569128855, 22945.655815162787, 1101.9008289254007, 1105.4693747001484, 1106.1261603333437, 1107.3521989636154, 1109.367660433545, 12122.939385303018, 12525.874741252654, 12564.183328083645, 12639.87360126387, 12639.93680031594, 605812.0, 635851.0, 641630.0, 652292.0, 657005.0, 130577.34711326382, 133661.0, 134924.0, 136245.0, 137137.0, 1077.9920774076263, 1241.6088931986396, 1245.894099001577, 1253.3294687342227, 1256.7989121740661, 22939.65590516177, 23522.64716029227, 23711.288661340186, 23778.16776412817, 23841.52316953686, 65216.347836522924, 65394.0, 65421.0, 66750.0, 68382.65808670924, 88919.55540222257, 92741.0, 93478.0, 94783.0, 95111.52444237734, 15949.043057416593, 16292.266847992107, 16345.754813677566, 16376.0, 16384.180790960167, 25410.618840717772, 25573.74426255788, 25610.231693049238, 25615.359616009748, 25848.224553263433, 20222.696659550413, 20486.702885331466, 20689.482762931046, 20737.79262207419, 21003.369898903056, 16992.915035424743, 17318.393856215476, 18246.361377351728, 18379.81620183781, 19021.0, 21234.0, 21345.67981480248, 21494.14023439045, 21703.34889953304, 21920.451988699773, 15621.609459763597, 15956.122413266918, 16056.197190140212, 16124.919375403517, 16314.18429078565, 20348.389548313575, 20553.89723051375, 20705.0, 20771.0, 20833.791662083186], "value": 13057.446237523736, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.2532308522652, 1521.9819676055215, 1522.2921341576164, 1533.2180587900175, 1537.7847101405805, 16381.672366552677, 16941.407050753227, 16956.4065257716, 17024.91487542562, 17269.222884970175, 17002.064886431242, 17199.140042997864, 17244.361785630375, 17274.2226599803, 17339.653206935855, 2743.4513097380504, 2781.666200055986, 2785.8885644574193, 2788.6793018802896, 2791.2184588315304, 2730.931726706828, 2813.549832026876, 2817.7182281771866, 2830.6461692288462, 2839.1340641104457, 2769.806113572051, 2778.9444211115765, 2786.9721302786916, 2789.1771927281397, 2791.1207969489647, 2640.6435131257226, 2709.295583148376, 2711.30861630284, 2713.4030513287044, 2717.144099608617, 2645.8280211786364, 2648.0864101884818, 2659.6941351744604, 2665.506881226979, 2687.9596806047925, 15829.762553561706, 16090.195490225558, 16109.436169734121, 16203.756943645854, 16314.918425407915, 8213.0, 8500.44747091437, 8516.318694504425, 8532.23209911111, 8547.102554231778, 5705.258316418881, 5959.582829201958, 6104.236970378703, 6114.724837382329, 6127.264728232641, 2081.916723331065, 2121.331780489141, 2129.7976692214215, 2177.713400929018, 2184.2901057156487, 28904.71095289041, 29192.708072919213, 29277.12168634923, 29296.70703292961, 30581.38837223243, 7319.670614822346, 7331.596762178131, 7351.301626345488, 7477.925220747723, 7540.660670269851, 19362.0, 19390.4182874514, 19420.126094325507, 19453.416397507815, 19495.707564386546, 8106.47307924983, 8347.791305217419, 8355.958220208982, 8360.0, 8363.958180209182, 6442.871142577216, 6662.56693314933, 6849.0, 7702.653380597886, 7757.185495523002, 5512.3385193777, 5647.717614119278, 5651.660900345993, 5688.23208866806, 5698.544116470673, 1581.3279356273538, 1583.8812089093365, 1585.603599100225, 1588.682263547272, 1597.7936657823268, 961.6345788600287, 972.7179118055684, 978.6738968697455, 997.1623835977814, 999.3304485994402, 8274.29668478174, 8301.916980830236, 8303.335733141334, 8306.584670766559, 10077.294589378673, 1047.7642530430744, 1059.7350662334416, 1069.7004838645191, 1070.1118072000222, 1086.603389762731, 5514.393416724157, 5539.41836107211, 5555.944440555623, 5721.685307308138, 5736.311642602915, 23669.171578994654, 24058.63912041321, 24539.3865153369, 24722.13472528453, 24760.628590571156, 23629.172978945997, 24408.0, 24913.252602421595, 25062.74937250604, 25075.37311567189, 12443.502259909683, 12505.812412813815, 12834.67913302175, 12858.292793896238, 13114.213147211007, 9632.22942164611, 9697.951510242403, 9791.559379828068, 10046.69859904204, 10157.0, 20572.868492233072, 20814.37556873296, 20872.0, 20944.266950657264, 21250.89374553117, 21291.36125916225, 21709.131634734433, 21799.237026703988, 21874.67187992213, 21963.341099767033, 13537.79693304621, 13641.113327633671, 13728.725425491633, 13921.4431422746, 13953.930230348784, 15393.230338483256, 15479.303431345841, 15582.220888955733, 15613.609659758142, 15630.140342280829, 12448.688782780502, 12466.0, 12509.0, 12815.807762883751, 12879.291638959947, 10867.945660271647, 11027.503762330554, 11093.0, 11142.665720028412, 11221.438928053401, 11110.722231944266, 11199.328040317607, 11259.437028148395, 11269.154813388865, 11332.263402878787, 7654.2345765423015, 7873.291403773459, 7880.645370958206, 7887.171846955987, 7891.526508409514, 8190.099089100075, 8224.83550329002, 8326.375521836007, 8376.916230837858, 8438.029626593021], "value": 7267.99192319538, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.507557971087, 543.5760107116449, 564.6978866306526, 565.8330792416236, 576.6914700635158, 6725.730970761176, 6766.7181417012835, 6787.7624283150035, 6793.694283757237, 6831.2485626581165, 6430.389113034266, 6480.384363485473, 6557.83605409865, 6635.170603674541, 6653.467722582194, 5213.452587478317, 5246.5278124968745, 5255.369355677324, 5281.366236051679, 5335.466453354672, 5055.368078990126, 5226.7717086484645, 5234.18870075139, 5238.161894096946, 5299.735013249341, 5162.974185129069, 5164.354455693038, 5170.2503137045105, 5205.86985325368, 5218.582513398938, 5157.2779810826705, 5195.142801437758, 5221.634485586011, 5249.186376111708, 5285.127953887604, 4712.340272361872, 4726.2201736713405, 4726.409198850144, 4749.715017098985, 4755.167845627001, 6503.967480162616, 6505.0, 6555.737770489175, 6558.606483610999, 6615.50383721218, 6182.341593114958, 6224.221972253423, 6242.937570624281, 6246.687665616746, 6287.151234583318, 6953.096097507342, 7048.330408611174, 7093.326134017312, 7122.2521635228595, 7130.144382674061, 5263.578913686896, 5299.906536917896, 5342.27879236302, 5347.491988261109, 5364.302640656729, 7396.260373962615, 7400.740974065882, 7415.925840741577, 7458.179600243969, 7496.8125796854965, 5418.945810541844, 5431.375391829911, 5437.483439073281, 5462.0441422751655, 5516.0, 5464.7814087436855, 5487.176923461513, 5501.817109321435, 5560.221568980346, 5564.4991950724625, 6144.139820425144, 6193.907091393633, 6196.628202307876, 6202.131701561785, 6227.595206311576, 7060.92939070613, 7091.219965803757, 7097.503174777716, 7130.786076417612, 7162.606056666829, 1814.6642871068896, 1826.506843152368, 1886.302068234762, 1895.649304878602, 1930.7739585363395, 1300.219868079164, 1306.9285182985075, 1326.4893016188837, 1335.2255691698792, 1353.1339942436848, 6113.0, 6134.263888333339, 6176.81469555914, 6216.968915155395, 6250.499960003189, 7013.473989450709, 7066.4807066479925, 7072.292770722886, 7185.568865868065, 7296.0, 6096.451319381188, 6213.689315534205, 6221.346758590374, 6268.0, 6276.278228003838, 3718.4236443351324, 3786.2427514497213, 3831.6397678823614, 3835.4438606402473, 3879.476270703475, 7062.929370706227, 7096.964515177494, 7101.431885449152, 7110.3600675939415, 7145.214026457085, 7446.7021319146625, 7464.290892365217, 7504.287092726182, 7510.248975102555, 7512.21121782205, 7019.824504387431, 7030.3321184487395, 7049.29507049291, 7073.418202356206, 7103.822404439827, 7314.378277846443, 7314.597697126709, 7344.375728062961, 7361.447891408059, 7394.8890766639615, 7219.422446204396, 7248.492605517563, 7285.7814265572115, 7349.77950661481, 7379.594122323324, 6980.162380514371, 7075.5047146699235, 7095.361417472453, 7103.289671032855, 7138.535995160298, 5415.783368665367, 5486.094794359045, 5487.725613719218, 5505.421930697219, 5534.03154447962, 7009.1589009319205, 7161.355478007004, 7215.629030484191, 7249.311315425026, 7296.963515182602, 6765.391114799691, 6834.521583489108, 6890.758823441155, 6910.896336554855, 7021.332973367522, 6858.931410685829, 6891.620960847202, 6913.340798208495, 6919.619420931897, 7014.0, 6805.659717014229, 6836.28219036994, 6848.911082120001, 6922.757703480354, 6935.479839011993, 6948.861022779415, 6985.615791131537, 6991.580505169706, 7013.684384202621, 7043.2604576518725, 6740.022696708852, 6785.0, 6834.214065382345, 6839.487038472234, 6861.176658800776], "value": 5437.33723404272, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [962.3552220012591, 984.0897170117639, 1000.5397517142114, 1008.0826447932374, 1033.2199189611842, 18548.258069677213, 18969.430917072492, 19029.429117126474, 19054.618907621858, 21966.231181908635, 19798.70301945472, 20372.083256253467, 20534.17863285467, 20539.28112516062, 20636.277730279442, 987.0, 1024.2113572549133, 1030.4538594544904, 1031.2471895516278, 1034.0693375961657, 1047.5024363427349, 1074.6722249713835, 1102.6747109602647, 1103.0568863621609, 1103.5971870267374, 1160.22265082395, 1166.422620802704, 1169.8713141554422, 1170.29197335612, 1170.836082948388, 1158.160333758023, 1159.269660114131, 1160.1183100843336, 1160.4777849967547, 1162.1167912386563, 853.8676505141713, 854.179987212277, 856.2678909532323, 857.2499063319596, 857.2713193785232, 19021.334253301207, 19562.119704613327, 19975.50061248466, 20098.69851952222, 20192.596148076955, 22213.111475540958, 22397.785306669142, 22582.20962266313, 22779.08883644652, 22920.31239062814, 18391.72412413815, 18482.0, 18500.444986650422, 18573.257069717194, 18790.060496975235, 4013.9397909031386, 4019.5578486366476, 4033.798310084484, 4035.858744943913, 4038.0510580013674, 148440.0, 148572.99999999892, 149577.0, 150057.0, 151818.0, 15952.92023539875, 16017.839821601869, 16199.838001620068, 16206.513804585882, 16368.754468682979, 32855.83572082124, 33586.83206583952, 33689.0, 33770.662293377245, 33818.323633527194, 8642.22220000203, 8973.461592304482, 9045.276377889884, 9699.806003879883, 9981.850272245922, 4980.975095124501, 5037.622178336639, 5052.823151189691, 5065.822696205615, 5068.0370729562, 5688.175214593861, 5781.508571771446, 5790.305163380337, 5808.593398462067, 5811.186433899257, 1170.2334970594275, 1202.9218100823418, 1204.5482943896038, 1206.3666575047994, 1207.2153100484754, 756.1644382956754, 766.371575308253, 773.307889438949, 783.5494590610422, 786.09991559665, 13505.459781608626, 13765.242911639756, 13913.165210087225, 14456.843452523983, 15480.458183963507, 776.8834674798825, 779.746582360735, 780.2860382749834, 789.9723509677134, 794.3089512124374, 5977.372375900555, 6258.280297765724, 6395.808125756234, 6451.838704032437, 6493.51298652603, 123797.0, 125613.0, 126204.0, 127778.0, 129521.35239323744, 109377.0, 111186.0, 112895.0, 113353.0, 117787.41106294414, 105126.47436762767, 105235.0, 105999.0, 106109.46945265224, 110236.0, 14511.1293322401, 14673.0, 14816.925915370353, 14904.25478726081, 15026.774598381251, 48882.0, 49099.26351104665, 49274.75362623164, 50591.49408505868, 50770.49229507805, 65376.0, 66254.33745662482, 66416.0, 66704.33295666981, 68299.3170068293, 18133.00268485246, 18421.53946151357, 20447.38657840267, 20724.58550828945, 20742.06660700242, 20470.69293960562, 20786.27248046311, 20879.791202088392, 20899.68650470214, 21026.369208923756, 20720.896395517924, 20931.16275348969, 21027.68458473155, 21392.251271205434, 21970.34088977333, 17079.7438038432, 17193.398231061852, 19361.709574356115, 19444.222231110598, 19557.70663440078, 19407.611847763248, 19525.70711439358, 19593.02034898278, 19823.801761982195, 21578.6763198549, 14171.574852754435, 15123.0, 15246.31391587359, 15342.079475231521, 15386.076835389913, 15408.84591154074, 15737.842621573638, 15801.525954221392, 15827.604309892346, 15950.282237299582], "value": 10092.471547212897, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1433.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 99.0, 105.0, 105.0, 105.0, 107.0, 99.0, 105.0, 106.0], "value": 103.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 121.0, 149.0, 181.0, 117.0, 137.0, 149.0, 111.0, 161.0, 112.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 160.0, 163.0, 163.0, 161.0, 162.0, 166.0, 170.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 139.0, 74.0, 72.0, 72.0, 100.0, 96.0, 73.0], "value": 84.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 125.0, 81.0, 77.0, 78.0, 130.0, 78.0, 78.0, 122.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 115.0, 113.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 157.0, 153.0, 152.0, 153.0, 152.0, 153.0, 153.0, 154.0], "value": 153.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 43.0, 43.0, 44.0, 44.0, 47.0, 49.0, 44.0, 49.0, 44.0], "value": 45.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 36.0, 37.0, 37.0, 37.0, 36.0, 37.0, 37.0, 36.0, 37.0], "value": 36.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 72.0, 74.0, 81.0, 74.0, 72.0, 73.0, 72.0, 71.0, 72.0], "value": 73.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 115.0, 111.0, 116.0, 111.0, 119.0, 112.0, 111.0, 153.0], "value": 117.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 161.0, 155.0, 154.0, 153.0, 156.0, 157.0, 162.0, 158.0, 156.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 66.0, 60.0, 60.0, 59.0, 59.0, 60.0, 59.0, 57.0], "value": 60.2, "unit": "score"}]}]} 16:35:23 INFO - Return code: 0 16:35:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:35:23 INFO - # TBPL SUCCESS # 16:35:23 INFO - Running post-action listener: _package_coverage_data 16:35:23 INFO - Running post-action listener: _resource_record_post_action 16:35:23 INFO - [mozharness: 2017-09-03 23:35:23.513055Z] Finished run-tests step (success) 16:35:23 INFO - Running post-run listener: _resource_record_post_run 16:35:23 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291811328; Read time: 108; Write time: 150452 16:35:23 INFO - TinderboxPrint: CPU usage
13.1% 16:35:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 108 16:35:23 INFO - TinderboxPrint: I/O write bytes / time
291,811,328 / 150,452 16:35:23 INFO - TinderboxPrint: CPU idle
8,226.7 (86.9%) 16:35:23 INFO - TinderboxPrint: CPU user
1,188.4 (12.6%) 16:35:23 INFO - TinderboxPrint: Swap in / out
0 / 0 16:35:23 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 3321856; Read time: 0; Write time: 244 16:35:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:35:23 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 4096; Write bytes: 278716416; Read time: 108; Write time: 149428 16:35:24 INFO - Running post-run listener: _upload_blobber_files 16:35:24 INFO - Blob upload gear active. 16:35:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:35:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:35:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:35:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:35:24 INFO - (blobuploader) - INFO - Open directory for files ... 16:35:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:35:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:35:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:28 INFO - (blobuploader) - INFO - Done attempting. 16:35:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:35:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:35:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:28 INFO - (blobuploader) - INFO - Done attempting. 16:35:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:35:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:35:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:29 INFO - (blobuploader) - INFO - Done attempting. 16:35:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:35:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:35:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:30 INFO - (blobuploader) - INFO - Done attempting. 16:35:30 INFO - (blobuploader) - INFO - Iteration through files over. 16:35:30 INFO - Return code: 0 16:35:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:35:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:35:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93f8fd995dd0b79b7b1fa493fe4105124ff074b948663a7d0dfdbc34edf9322e341c75fc4f8f0683e0d3b97df0ce3ff3cc3390fc3b1445495ef074b3fea4d5bd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f573dba2dc2e10b8ebfb582e9da7d5c556e690c3a1d80b69f341bbfaf6898f2f758aec69913ab785c279fc5bca11040d09c8f5e8551cc95100255bd1ac60428a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a38fa10f86e69b5e91bf9dbdd5a03e6f9ab15d93911253fdfc3d7be5e26bd8605c1d847d80742980c8cab3fce1dad227c929bb6843b36fac5c5fc8cbb2515bb0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05307bb0c09381ad0e3c3ac43f682271bd8773b281273d3a12bb35930ee93189ae7cb84b002546a18075002b4eb684451795e510b02bed9e626a5e1a100f6f10"} 16:35:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:35:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:35:30 INFO - Contents: 16:35:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93f8fd995dd0b79b7b1fa493fe4105124ff074b948663a7d0dfdbc34edf9322e341c75fc4f8f0683e0d3b97df0ce3ff3cc3390fc3b1445495ef074b3fea4d5bd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f573dba2dc2e10b8ebfb582e9da7d5c556e690c3a1d80b69f341bbfaf6898f2f758aec69913ab785c279fc5bca11040d09c8f5e8551cc95100255bd1ac60428a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a38fa10f86e69b5e91bf9dbdd5a03e6f9ab15d93911253fdfc3d7be5e26bd8605c1d847d80742980c8cab3fce1dad227c929bb6843b36fac5c5fc8cbb2515bb0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05307bb0c09381ad0e3c3ac43f682271bd8773b281273d3a12bb35930ee93189ae7cb84b002546a18075002b4eb684451795e510b02bed9e626a5e1a100f6f10"} 16:35:30 INFO - Running post-run listener: copy_logs_to_upload_dir 16:35:30 INFO - Copying logs to upload dir... 16:35:30 INFO - mkdir: /builds/slave/test/build/upload/logs 16:35:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.552872 ========= master_lag: -0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-09-03 16:35:30.891465) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 16:35:30.896461) ========= 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=dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93f8fd995dd0b79b7b1fa493fe4105124ff074b948663a7d0dfdbc34edf9322e341c75fc4f8f0683e0d3b97df0ce3ff3cc3390fc3b1445495ef074b3fea4d5bd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f573dba2dc2e10b8ebfb582e9da7d5c556e690c3a1d80b69f341bbfaf6898f2f758aec69913ab785c279fc5bca11040d09c8f5e8551cc95100255bd1ac60428a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a38fa10f86e69b5e91bf9dbdd5a03e6f9ab15d93911253fdfc3d7be5e26bd8605c1d847d80742980c8cab3fce1dad227c929bb6843b36fac5c5fc8cbb2515bb0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05307bb0c09381ad0e3c3ac43f682271bd8773b281273d3a12bb35930ee93189ae7cb84b002546a18075002b4eb684451795e510b02bed9e626a5e1a100f6f10"} build_url:https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011685 build_url: 'https://queue.taskcluster.net/v1/task/cbMG52T8T9aAD4VkwEjQyA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93f8fd995dd0b79b7b1fa493fe4105124ff074b948663a7d0dfdbc34edf9322e341c75fc4f8f0683e0d3b97df0ce3ff3cc3390fc3b1445495ef074b3fea4d5bd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f573dba2dc2e10b8ebfb582e9da7d5c556e690c3a1d80b69f341bbfaf6898f2f758aec69913ab785c279fc5bca11040d09c8f5e8551cc95100255bd1ac60428a", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a38fa10f86e69b5e91bf9dbdd5a03e6f9ab15d93911253fdfc3d7be5e26bd8605c1d847d80742980c8cab3fce1dad227c929bb6843b36fac5c5fc8cbb2515bb0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05307bb0c09381ad0e3c3ac43f682271bd8773b281273d3a12bb35930ee93189ae7cb84b002546a18075002b4eb684451795e510b02bed9e626a5e1a100f6f10"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-03 16:35:30.930232) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:35:30.930580) ========= 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=dac658600622fd96716c9acf000001b3-1504479923.517576-1032093718 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004541 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-03 16:35:31.013102) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 16:35:31.013420) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-03 16:35:31.013738) ========= ========= Total master_lag: 0.12 =========