builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-052 starttime: 1501000633.5 results: success (0) revision: 32d9d1e81cc607320a36391845917f645f7a7f72 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:13.497451) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:13.498111) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:13.498390) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:13.514755) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:13.515187) ========= 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=826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-25 09:37:13-- 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% 215M=0s 2017-07-25 09:37:13 (215 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121939 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:13.655398) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:13.655986) ========= 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=826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013770 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:13.709717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:13.713084) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 32d9d1e81cc607320a36391845917f645f7a7f72 --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 32d9d1e81cc607320a36391845917f645f7a7f72 --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=826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-25 09:37:13,946 truncating revision to first 12 chars 2017-07-25 09:37:13,946 Setting DEBUG logging. 2017-07-25 09:37:13,946 attempt 1/10 2017-07-25 09:37:13,946 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/32d9d1e81cc6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-25 09:37:14,277 unpacking tar archive at: mozilla-central-32d9d1e81cc6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.625956 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:14.359593) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:14.359966) ========= script_repo_revision: 32d9d1e81cc607320a36391845917f645f7a7f72 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:14.360375) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:14.360670) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-25 09:37:14.369344) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-07-25 09:37:14.369628) ========= /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=826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:37:14 INFO - MultiFileLogger online at 20170725 09:37:14 in /builds/slave/test 09:37:14 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 09:37:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:37:14 INFO - {'append_to_log': False, 09:37:14 INFO - 'base_work_dir': '/builds/slave/test', 09:37:14 INFO - 'blob_upload_branch': 'Firefox', 09:37:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:37:14 INFO - 'branch': 'Firefox', 09:37:14 INFO - 'buildbot_json_path': 'buildprops.json', 09:37:14 INFO - 'code_coverage': False, 09:37:14 INFO - 'config_files': ('talos/linux_config.py',), 09:37:14 INFO - 'default_actions': ('clobber', 09:37:14 INFO - 'read-buildbot-config', 09:37:14 INFO - 'download-and-extract', 09:37:14 INFO - 'populate-webroot', 09:37:14 INFO - 'create-virtualenv', 09:37:14 INFO - 'install', 09:37:14 INFO - 'setup-mitmproxy', 09:37:14 INFO - 'run-tests'), 09:37:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:37:14 INFO - 'disable_ccov_upload': False, 09:37:14 INFO - 'download_minidump_stackwalk': True, 09:37:14 INFO - 'download_symbols': 'ondemand', 09:37:14 INFO - 'enable_stylo': False, 09:37:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:37:14 INFO - 'tooltool.py': '/tools/tooltool.py', 09:37:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:37:14 INFO - '/tools/misc-python/virtualenv.py')}, 09:37:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:37:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:37:14 INFO - 'gecko_profile': False, 09:37:14 INFO - 'gecko_profile_interval': 0, 09:37:14 INFO - 'installer_path': 'installer.exe', 09:37:14 INFO - 'log_level': 'info', 09:37:14 INFO - 'log_name': 'talos', 09:37:14 INFO - 'log_to_console': True, 09:37:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:37:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:37:14 INFO - 'opt_config_files': (), 09:37:14 INFO - 'pip_index': False, 09:37:14 INFO - 'suite': 'dromaeojs-e10s', 09:37:14 INFO - 'system_bits': '32', 09:37:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:37:14 INFO - 'title': 'talos-linux64-ix-052', 09:37:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:37:14 INFO - 'use_talos_json': True, 09:37:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:37:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:37:14 INFO - 'work_dir': 'build'} 09:37:14 INFO - [mozharness: 2017-07-25 16:37:14.701047Z] Running clobber step. 09:37:14 INFO - Running pre-action listener: _resource_record_pre_action 09:37:14 INFO - Running main action method: clobber 09:37:14 INFO - rmtree: /builds/slave/test/build 09:37:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:37:16 INFO - Running post-action listener: _resource_record_post_action 09:37:16 INFO - [mozharness: 2017-07-25 16:37:16.258874Z] Finished clobber step (success) 09:37:16 INFO - [mozharness: 2017-07-25 16:37:16.258975Z] Running read-buildbot-config step. 09:37:16 INFO - Running pre-action listener: _resource_record_pre_action 09:37:16 INFO - Running main action method: read_buildbot_config 09:37:16 INFO - Using buildbot properties: 09:37:16 INFO - { 09:37:16 INFO - "project": "", 09:37:16 INFO - "product": "firefox", 09:37:16 INFO - "who": "cbook@mozilla.com", 09:37:16 INFO - "installer_path": "public/build/target.tar.bz2", 09:37:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 09:37:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 09:37:16 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 09:37:16 INFO - "stage_platform": "linux64-stylo", 09:37:16 INFO - "basedir": "/builds/slave/test", 09:37:16 INFO - "buildnumber": 1, 09:37:16 INFO - "platform": "ubuntu64_hw_stylo", 09:37:16 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:37:16 INFO - "slavebuilddir": "test", 09:37:16 INFO - "taskId": "OwCbn476TCy_zIBZ2r1SvQ", 09:37:16 INFO - "branch": "mozilla-central", 09:37:16 INFO - "script_repo_revision": "production", 09:37:16 INFO - "revision": "32d9d1e81cc607320a36391845917f645f7a7f72", 09:37:16 INFO - "slavename": "talos-linux64-ix-052", 09:37:16 INFO - "repo_path": "mozilla-central" 09:37:16 INFO - } 09:37:16 INFO - Finding installer, test and symbols from parent task. 09:37:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OwCbn476TCy_zIBZ2r1SvQ'}, attempt #1 09:37:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OwCbn476TCy_zIBZ2r1SvQ'}, attempt #1 09:37:17 INFO - Task dependencies: fWUCQvL-S1mphvFnR8y4xA 09:37:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA'}, attempt #1 09:37:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.tar.bz2 09:37:17 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/test_packages.json 09:37:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.crashreporter-symbols.zip 09:37:17 INFO - Running post-action listener: _resource_record_post_action 09:37:17 INFO - [mozharness: 2017-07-25 16:37:17.613037Z] Finished read-buildbot-config step (success) 09:37:17 INFO - [mozharness: 2017-07-25 16:37:17.613259Z] Running download-and-extract step. 09:37:17 INFO - Running pre-action listener: _resource_record_pre_action 09:37:17 INFO - Running main action method: download_and_extract 09:37:17 INFO - mkdir: /builds/slave/test/build/tests 09:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:17 INFO - trying https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.test_packages.json 09:37:17 INFO - Downloading https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:37:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:37:18 INFO - Downloaded 989 bytes. 09:37:18 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:37:18 INFO - Using the following test package requirements: 09:37:18 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:37:18 INFO - u'common': [u'target.common.tests.zip'], 09:37:18 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:37:18 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:37:18 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:37:18 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:37:18 INFO - u'mozbase': [u'target.common.tests.zip'], 09:37:18 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:37:18 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:37:18 INFO - u'web-platform': [u'target.common.tests.zip', 09:37:18 INFO - u'target.web-platform.tests.tar.gz'], 09:37:18 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:37:18 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:37:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.common.tests.zip 09:37:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:37:18 INFO - Fetch https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.common.tests.zip into memory 09:37:19 INFO - Content-Length response header: 72762047 09:37:19 INFO - Bytes received: 72762047 09:37:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:37:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.common.tests.zip 09:37:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:37:30 INFO - Fetch https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.common.tests.zip into memory 09:37:31 INFO - Content-Length response header: 72762047 09:37:31 INFO - Bytes received: 72762047 09:37:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.talos.tests.zip 09:37:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:37:44 INFO - Fetch https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.talos.tests.zip into memory 09:37:44 INFO - Content-Length response header: 14498866 09:37:44 INFO - Bytes received: 14498866 09:37:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:45 INFO - trying https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.tar.bz2 09:37:45 INFO - Downloading https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:37:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:37:46 INFO - Downloaded 62041065 bytes. 09:37:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.tar.bz2 09:37:46 INFO - mkdir: /builds/slave/test/properties 09:37:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:46 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:46 INFO - Contents: 09:37:46 INFO - build_url:https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.tar.bz2 09:37:46 INFO - Running post-action listener: _resource_record_post_action 09:37:46 INFO - Running post-action listener: set_extra_try_arguments 09:37:46 INFO - [mozharness: 2017-07-25 16:37:46.380705Z] Finished download-and-extract step (success) 09:37:46 INFO - [mozharness: 2017-07-25 16:37:46.380803Z] Running populate-webroot step. 09:37:46 INFO - Running pre-action listener: _resource_record_pre_action 09:37:46 INFO - Running main action method: populate_webroot 09:37:46 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:37:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:37:46 INFO - 'tests': ['damp', 'tps']}, 09:37:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:37:46 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:37:46 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:37:46 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:46 INFO - 'ts_paint', 09:37:46 INFO - 'tpaint', 09:37:46 INFO - 'sessionrestore', 09:37:46 INFO - 'sessionrestore_many_windows', 09:37:46 INFO - 'sessionrestore_no_auto_restore', 09:37:46 INFO - 'tabpaint']}, 09:37:46 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:37:46 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:37:46 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:37:46 INFO - 'talos_options': ['--mitmproxy', 09:37:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:37:46 INFO - '--firstNonBlankPaint'], 09:37:46 INFO - 'tests': ['quantum_pageload_google', 09:37:46 INFO - 'quantum_pageload_youtube', 09:37:46 INFO - 'quantum_pageload_amazon', 09:37:46 INFO - 'quantum_pageload_facebook']}, 09:37:46 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:37:46 INFO - 'talos_options': ['--stylo', 09:37:46 INFO - '--mitmproxy', 09:37:46 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:37:46 INFO - '--firstNonBlankPaint'], 09:37:46 INFO - 'tests': ['quantum_pageload_google', 09:37:46 INFO - 'quantum_pageload_youtube', 09:37:46 INFO - 'quantum_pageload_amazon', 09:37:46 INFO - 'quantum_pageload_facebook']}, 09:37:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:46 INFO - 'tsvgr_opacity', 09:37:46 INFO - 'tart', 09:37:46 INFO - 'tscrollx', 09:37:46 INFO - 'cart', 09:37:46 INFO - 'tsvg_static']}, 09:37:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:37:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:37:46 INFO - 'talos_options': ['--xperf_path', 09:37:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:46 INFO - 'tests': ['tp5n']}}} 09:37:46 INFO - Running post-action listener: _resource_record_post_action 09:37:46 INFO - [mozharness: 2017-07-25 16:37:46.388089Z] Finished populate-webroot step (success) 09:37:46 INFO - [mozharness: 2017-07-25 16:37:46.388179Z] Running create-virtualenv step. 09:37:46 INFO - Running pre-action listener: _resource_record_pre_action 09:37:46 INFO - Running main action method: create_virtualenv 09:37:46 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:37:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:37:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:46 INFO - Using real prefix '/usr' 09:37:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:46 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:48 INFO - Installing pip.................done. 09:37:48 INFO - Return code: 0 09:37:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:37:49 INFO - Reading from file tmpfile_stdout 09:37:49 INFO - Output received: 09:37:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:37:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:37:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:37:49 INFO - Using env: {'DISPLAY': ':0', 09:37:49 INFO - 'HOME': '/home/cltbld', 09:37:49 INFO - 'LANG': 'en_US.UTF-8', 09:37:49 INFO - 'LANGUAGE': 'en_US:en', 09:37:49 INFO - 'LOGNAME': 'cltbld', 09:37:49 INFO - 'MAIL': '/var/mail/cltbld', 09:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:49 INFO - 'MOZ_NO_REMOTE': '1', 09:37:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:49 INFO - 'NO_EM_RESTART': '1', 09:37:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:49 INFO - 'PWD': '/builds/slave/test', 09:37:49 INFO - 'SHELL': '/bin/bash', 09:37:49 INFO - 'SHLVL': '1', 09:37:49 INFO - 'TERM': 'linux', 09:37:49 INFO - 'TMOUT': '86400', 09:37:49 INFO - 'USER': 'cltbld', 09:37:49 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:49 INFO - '_': '/tools/buildbot/bin/python'} 09:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:37:49 INFO - Cleaning up... 09:37:49 INFO - Return code: 0 09:37:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:37:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:37:49 INFO - Using env: {'DISPLAY': ':0', 09:37:49 INFO - 'HOME': '/home/cltbld', 09:37:49 INFO - 'LANG': 'en_US.UTF-8', 09:37:49 INFO - 'LANGUAGE': 'en_US:en', 09:37:49 INFO - 'LOGNAME': 'cltbld', 09:37:49 INFO - 'MAIL': '/var/mail/cltbld', 09:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:49 INFO - 'MOZ_NO_REMOTE': '1', 09:37:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:49 INFO - 'NO_EM_RESTART': '1', 09:37:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:49 INFO - 'PWD': '/builds/slave/test', 09:37:49 INFO - 'SHELL': '/bin/bash', 09:37:49 INFO - 'SHLVL': '1', 09:37:49 INFO - 'TERM': 'linux', 09:37:49 INFO - 'TMOUT': '86400', 09:37:49 INFO - 'USER': 'cltbld', 09:37:49 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:49 INFO - '_': '/tools/buildbot/bin/python'} 09:37:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:49 INFO - Downloading/unpacking psutil>=3.1.1 09:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:37:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:50 INFO - Installing collected packages: psutil 09:37:50 INFO - Running setup.py install for psutil 09:37:50 INFO - building 'psutil._psutil_linux' extension 09:37:50 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 09:37:51 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 09:37:51 INFO - building 'psutil._psutil_posix' extension 09:37:51 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 09:37:51 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 09:37:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:37:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:37:51 INFO - Successfully installed psutil 09:37:51 INFO - Cleaning up... 09:37:51 INFO - Return code: 0 09:37:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:37:51 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')]} 09:37:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:51 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')]} 09:37:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:51 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 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:37:51 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 09:37:51 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 09:37:51 INFO - Using env: {'DISPLAY': ':0', 09:37:51 INFO - 'HOME': '/home/cltbld', 09:37:51 INFO - 'LANG': 'en_US.UTF-8', 09:37:51 INFO - 'LANGUAGE': 'en_US:en', 09:37:51 INFO - 'LOGNAME': 'cltbld', 09:37:51 INFO - 'MAIL': '/var/mail/cltbld', 09:37:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:51 INFO - 'MOZ_NO_REMOTE': '1', 09:37:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:51 INFO - 'NO_EM_RESTART': '1', 09:37:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:51 INFO - 'PWD': '/builds/slave/test', 09:37:51 INFO - 'SHELL': '/bin/bash', 09:37:51 INFO - 'SHLVL': '1', 09:37:51 INFO - 'TERM': 'linux', 09:37:51 INFO - 'TMOUT': '86400', 09:37:51 INFO - 'USER': 'cltbld', 09:37:51 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:37:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:51 INFO - '_': '/tools/buildbot/bin/python'} 09:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:37:51 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 09:37:51 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 09:37:51 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 09:37:51 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 09:37:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:37:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:37:52 INFO - Installing collected packages: mozsystemmonitor 09:37:52 INFO - Running setup.py install for mozsystemmonitor 09:37:52 INFO - Successfully installed mozsystemmonitor 09:37:52 INFO - Cleaning up... 09:37:52 INFO - Return code: 0 09:37:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:37: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')]} 09:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37: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')]} 09:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:37:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:37:52 INFO - Using env: {'DISPLAY': ':0', 09:37:52 INFO - 'HOME': '/home/cltbld', 09:37:52 INFO - 'LANG': 'en_US.UTF-8', 09:37:52 INFO - 'LANGUAGE': 'en_US:en', 09:37:52 INFO - 'LOGNAME': 'cltbld', 09:37:52 INFO - 'MAIL': '/var/mail/cltbld', 09:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:52 INFO - 'MOZ_NO_REMOTE': '1', 09:37:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:52 INFO - 'NO_EM_RESTART': '1', 09:37:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:52 INFO - 'PWD': '/builds/slave/test', 09:37:52 INFO - 'SHELL': '/bin/bash', 09:37:52 INFO - 'SHLVL': '1', 09:37:52 INFO - 'TERM': 'linux', 09:37:52 INFO - 'TMOUT': '86400', 09:37:52 INFO - 'USER': 'cltbld', 09:37:52 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:52 INFO - '_': '/tools/buildbot/bin/python'} 09:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:53 INFO - Downloading/unpacking jsonschema==2.5.1 09:37:53 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 09:37:53 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 09:37:53 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 09:37:53 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 09:37:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:37:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:54 INFO - Downloading functools32-3.2.3-2.tar.gz 09:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:37:54 INFO - warning: no files found matching '*.txt' 09:37:54 INFO - no previously-included directories found matching 'build' 09:37:54 INFO - no previously-included directories found matching 'dist' 09:37:54 INFO - no previously-included directories found matching '.git*' 09:37:54 INFO - Installing collected packages: jsonschema, functools32 09:37:54 INFO - Running setup.py install for functools32 09:37:54 INFO - warning: no files found matching '*.txt' 09:37:54 INFO - no previously-included directories found matching 'build' 09:37:54 INFO - no previously-included directories found matching 'dist' 09:37:54 INFO - no previously-included directories found matching '.git*' 09:37:54 INFO - Successfully installed jsonschema functools32 09:37:54 INFO - Cleaning up... 09:37:54 INFO - Return code: 0 09:37:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:37:54 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')]} 09:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:54 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')]} 09:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:54 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 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:37:54 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 09:37:54 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 09:37:54 INFO - Using env: {'DISPLAY': ':0', 09:37:54 INFO - 'HOME': '/home/cltbld', 09:37:54 INFO - 'LANG': 'en_US.UTF-8', 09:37:54 INFO - 'LANGUAGE': 'en_US:en', 09:37:54 INFO - 'LOGNAME': 'cltbld', 09:37:54 INFO - 'MAIL': '/var/mail/cltbld', 09:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:54 INFO - 'MOZ_NO_REMOTE': '1', 09:37:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:54 INFO - 'NO_EM_RESTART': '1', 09:37:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:54 INFO - 'PWD': '/builds/slave/test', 09:37:54 INFO - 'SHELL': '/bin/bash', 09:37:54 INFO - 'SHLVL': '1', 09:37:54 INFO - 'TERM': 'linux', 09:37:54 INFO - 'TMOUT': '86400', 09:37:54 INFO - 'USER': 'cltbld', 09:37:54 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:54 INFO - '_': '/tools/buildbot/bin/python'} 09:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:37:54 INFO - Cleaning up... 09:37:54 INFO - Return code: 0 09:37:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:37:54 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')]} 09:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:54 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')]} 09:37:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:54 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 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:37:54 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 09:37:54 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 09:37:54 INFO - Using env: {'DISPLAY': ':0', 09:37:54 INFO - 'HOME': '/home/cltbld', 09:37:54 INFO - 'LANG': 'en_US.UTF-8', 09:37:54 INFO - 'LANGUAGE': 'en_US:en', 09:37:54 INFO - 'LOGNAME': 'cltbld', 09:37:54 INFO - 'MAIL': '/var/mail/cltbld', 09:37:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:54 INFO - 'MOZ_NO_REMOTE': '1', 09:37:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:54 INFO - 'NO_EM_RESTART': '1', 09:37:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:54 INFO - 'PWD': '/builds/slave/test', 09:37:54 INFO - 'SHELL': '/bin/bash', 09:37:54 INFO - 'SHLVL': '1', 09:37:54 INFO - 'TERM': 'linux', 09:37:54 INFO - 'TMOUT': '86400', 09:37:54 INFO - 'USER': 'cltbld', 09:37:54 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:37:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:54 INFO - '_': '/tools/buildbot/bin/python'} 09:37:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:54 INFO - Downloading/unpacking blobuploader==1.2.4 09:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:56 INFO - Downloading blobuploader-1.2.4.tar.gz 09:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:37:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:37:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 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 09:37:56 INFO - Downloading docopt-0.6.1.tar.gz 09:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:37:56 INFO - Installing collected packages: blobuploader, requests, docopt 09:37:56 INFO - Running setup.py install for blobuploader 09:37:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:37:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:37:56 INFO - Running setup.py install for requests 09:37:57 INFO - Running setup.py install for docopt 09:37:57 INFO - Successfully installed blobuploader requests docopt 09:37:57 INFO - Cleaning up... 09:37:57 INFO - Return code: 0 09:37:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:57 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 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:37:57 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 09:37:57 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 09:37:57 INFO - Using env: {'DISPLAY': ':0', 09:37:57 INFO - 'HOME': '/home/cltbld', 09:37:57 INFO - 'LANG': 'en_US.UTF-8', 09:37:57 INFO - 'LANGUAGE': 'en_US:en', 09:37:57 INFO - 'LOGNAME': 'cltbld', 09:37:57 INFO - 'MAIL': '/var/mail/cltbld', 09:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:57 INFO - 'MOZ_NO_REMOTE': '1', 09:37:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:37:57 INFO - 'NO_EM_RESTART': '1', 09:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:57 INFO - 'PWD': '/builds/slave/test', 09:37:57 INFO - 'SHELL': '/bin/bash', 09:37:57 INFO - 'SHLVL': '1', 09:37:57 INFO - 'TERM': 'linux', 09:37:57 INFO - 'TMOUT': '86400', 09:37:57 INFO - 'USER': 'cltbld', 09:37:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:57 INFO - '_': '/tools/buildbot/bin/python'} 09:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:37:57 INFO - Running setup.py (path:/tmp/pip-TLOPQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:37:57 INFO - Running setup.py (path:/tmp/pip-ktt8nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:37:57 INFO - Running setup.py (path:/tmp/pip-EZH_TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:37:57 INFO - Running setup.py (path:/tmp/pip-9Rq9Xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:37:57 INFO - Running setup.py (path:/tmp/pip-FyT3sX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:37:57 INFO - Running setup.py (path:/tmp/pip-HD0UsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:37:57 INFO - Running setup.py (path:/tmp/pip-S5aX2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:37:57 INFO - Running setup.py (path:/tmp/pip-E9U4s2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:37:58 INFO - Running setup.py (path:/tmp/pip-Dq3LW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:37:58 INFO - Running setup.py (path:/tmp/pip-GBI0Q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:37:58 INFO - Running setup.py (path:/tmp/pip-ue0hQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:37:58 INFO - Running setup.py (path:/tmp/pip-9rCrrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:37:58 INFO - Running setup.py (path:/tmp/pip-UGzr5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:37:58 INFO - Running setup.py (path:/tmp/pip-oQl72Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:58 INFO - Running setup.py (path:/tmp/pip-c22Eaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:37:58 INFO - Running setup.py (path:/tmp/pip-Vbjups-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:37:58 INFO - Running setup.py (path:/tmp/pip-mGDQpz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:37:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:37:58 INFO - Running setup.py install for manifestparser 09:37:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:37:58 INFO - Running setup.py install for mozcrash 09:37:58 INFO - Running setup.py install for mozdebug 09:37:58 INFO - Running setup.py install for mozdevice 09:37:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozfile 09:37:59 INFO - Running setup.py install for mozhttpd 09:37:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozinfo 09:37:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozInstall 09:37:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozleak 09:37:59 INFO - Running setup.py install for mozlog 09:37:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for moznetwork 09:37:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:37:59 INFO - Running setup.py install for mozprocess 09:37:59 INFO - Running setup.py install for mozprofile 09:38:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Running setup.py install for mozrunner 09:38:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Running setup.py install for mozscreenshot 09:38:00 INFO - Running setup.py install for moztest 09:38:00 INFO - Running setup.py install for mozversion 09:38:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:00 INFO - Cleaning up... 09:38:00 INFO - Return code: 0 09:38:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:00 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 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:38:00 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 09:38:00 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 09:38:00 INFO - Using env: {'DISPLAY': ':0', 09:38:00 INFO - 'HOME': '/home/cltbld', 09:38:00 INFO - 'LANG': 'en_US.UTF-8', 09:38:00 INFO - 'LANGUAGE': 'en_US:en', 09:38:00 INFO - 'LOGNAME': 'cltbld', 09:38:00 INFO - 'MAIL': '/var/mail/cltbld', 09:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:00 INFO - 'MOZ_NO_REMOTE': '1', 09:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:00 INFO - 'NO_EM_RESTART': '1', 09:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:00 INFO - 'PWD': '/builds/slave/test', 09:38:00 INFO - 'SHELL': '/bin/bash', 09:38:00 INFO - 'SHLVL': '1', 09:38:00 INFO - 'TERM': 'linux', 09:38:00 INFO - 'TMOUT': '86400', 09:38:00 INFO - 'USER': 'cltbld', 09:38:00 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:00 INFO - '_': '/tools/buildbot/bin/python'} 09:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:00 INFO - Running setup.py (path:/tmp/pip-PArLNx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:00 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)) 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:00 INFO - Running setup.py (path:/tmp/pip-Q_DyYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:00 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)) 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:00 INFO - Running setup.py (path:/tmp/pip-u9LOrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:00 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)) 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:00 INFO - Running setup.py (path:/tmp/pip-cp3mlM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:00 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)) 09:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:00 INFO - Running setup.py (path:/tmp/pip-XgIOB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:01 INFO - Running setup.py (path:/tmp/pip-58NW2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:01 INFO - Running setup.py (path:/tmp/pip-ahjOpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:01 INFO - Running setup.py (path:/tmp/pip-ohcQ05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:01 INFO - Running setup.py (path:/tmp/pip-vsyORO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:01 INFO - Running setup.py (path:/tmp/pip-MyynkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:01 INFO - Running setup.py (path:/tmp/pip-IJr0uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:01 INFO - Running setup.py (path:/tmp/pip-qLPFab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:01 INFO - Running setup.py (path:/tmp/pip-59NLiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:01 INFO - Running setup.py (path:/tmp/pip-KBYqM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:01 INFO - Running setup.py (path:/tmp/pip-jRW31d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:01 INFO - Running setup.py (path:/tmp/pip-E2HOaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:01 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)) 09:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:01 INFO - Running setup.py (path:/tmp/pip-SUsZFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:01 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)) 09:38:01 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)) 09:38:01 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)) 09:38:01 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)) 09:38:01 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)) 09:38:01 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)) 09:38:01 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)) 09:38:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:03 INFO - Downloading blessings-1.6.tar.gz 09:38:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:03 INFO - Installing collected packages: blessings 09:38:03 INFO - Running setup.py install for blessings 09:38:03 INFO - Successfully installed blessings 09:38:03 INFO - Cleaning up... 09:38:03 INFO - Return code: 0 09:38:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:03 INFO - Reading from file tmpfile_stdout 09:38:03 INFO - Current package versions: 09:38:03 INFO - argparse == 1.2.1 09:38:03 INFO - blessings == 1.6 09:38:03 INFO - blobuploader == 1.2.4 09:38:03 INFO - docopt == 0.6.1 09:38:03 INFO - functools32 == 3.2.3-2 09:38:03 INFO - jsonschema == 2.5.1 09:38:03 INFO - manifestparser == 1.1 09:38:03 INFO - mozInstall == 1.13 09:38:03 INFO - mozcrash == 1.0 09:38:03 INFO - mozdebug == 0.1 09:38:03 INFO - mozdevice == 0.51 09:38:03 INFO - mozfile == 1.2 09:38:03 INFO - mozhttpd == 0.7 09:38:03 INFO - mozinfo == 0.10 09:38:03 INFO - mozleak == 0.1 09:38:03 INFO - mozlog == 3.5 09:38:03 INFO - moznetwork == 0.27 09:38:03 INFO - mozprocess == 0.25 09:38:03 INFO - mozprofile == 0.28 09:38:03 INFO - mozrunner == 6.13 09:38:03 INFO - mozscreenshot == 0.1 09:38:03 INFO - mozsystemmonitor == 0.3 09:38:03 INFO - moztest == 0.8 09:38:03 INFO - mozversion == 1.4 09:38:03 INFO - psutil == 3.1.1 09:38:03 INFO - requests == 1.2.3 09:38:03 INFO - wsgiref == 0.1.2 09:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:03 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 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:38:03 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 09:38:03 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 09:38:03 INFO - Using env: {'DISPLAY': ':0', 09:38:03 INFO - 'HOME': '/home/cltbld', 09:38:03 INFO - 'LANG': 'en_US.UTF-8', 09:38:03 INFO - 'LANGUAGE': 'en_US:en', 09:38:03 INFO - 'LOGNAME': 'cltbld', 09:38:03 INFO - 'MAIL': '/var/mail/cltbld', 09:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:03 INFO - 'MOZ_NO_REMOTE': '1', 09:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:03 INFO - 'NO_EM_RESTART': '1', 09:38:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:03 INFO - 'PWD': '/builds/slave/test', 09:38:03 INFO - 'SHELL': '/bin/bash', 09:38:03 INFO - 'SHLVL': '1', 09:38:03 INFO - 'TERM': 'linux', 09:38:03 INFO - 'TMOUT': '86400', 09:38:03 INFO - 'USER': 'cltbld', 09:38:03 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:03 INFO - '_': '/tools/buildbot/bin/python'} 09:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:03 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)) 09:38:03 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)) 09:38:03 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)) 09:38:03 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)) 09:38:03 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)) 09:38:03 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)) 09:38:03 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)) 09:38:03 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)) 09:38:03 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)) 09:38:03 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:04 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:38:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:38:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 09:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:38:05 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)) 09:38:05 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)) 09:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:38:05 INFO - Installing collected packages: marionette-driver, simplejson 09:38:05 INFO - Running setup.py install for marionette-driver 09:38:05 INFO - Running setup.py install for simplejson 09:38:05 INFO - building 'simplejson._speedups' extension 09:38:05 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 09:38:06 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 09:38:06 INFO - Successfully installed marionette-driver simplejson 09:38:06 INFO - Cleaning up... 09:38:06 INFO - Return code: 0 09:38:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24911f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x265da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2800d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, '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': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', '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 09:38:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:38:06 INFO - Using env: {'DISPLAY': ':0', 09:38:06 INFO - 'HOME': '/home/cltbld', 09:38:06 INFO - 'LANG': 'en_US.UTF-8', 09:38:06 INFO - 'LANGUAGE': 'en_US:en', 09:38:06 INFO - 'LOGNAME': 'cltbld', 09:38:06 INFO - 'MAIL': '/var/mail/cltbld', 09:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:06 INFO - 'MOZ_NO_REMOTE': '1', 09:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:06 INFO - 'NO_EM_RESTART': '1', 09:38:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:06 INFO - 'PWD': '/builds/slave/test', 09:38:06 INFO - 'SHELL': '/bin/bash', 09:38:06 INFO - 'SHLVL': '1', 09:38:06 INFO - 'TERM': 'linux', 09:38:06 INFO - 'TMOUT': '86400', 09:38:06 INFO - 'USER': 'cltbld', 09:38:06 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:06 INFO - '_': '/tools/buildbot/bin/python'} 09:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:06 INFO - Cleaning up... 09:38:06 INFO - Return code: 0 09:38:06 INFO - Running post-action listener: _resource_record_post_action 09:38:06 INFO - Running post-action listener: _start_resource_monitoring 09:38:06 INFO - Starting resource monitoring. 09:38:06 INFO - [mozharness: 2017-07-25 16:38:06.607100Z] Finished create-virtualenv step (success) 09:38:06 INFO - [mozharness: 2017-07-25 16:38:06.607571Z] Running install step. 09:38:06 INFO - Running pre-action listener: _resource_record_pre_action 09:38:06 INFO - Running main action method: install 09:38:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:06 INFO - Reading from file tmpfile_stdout 09:38:06 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:06 INFO - Reading from file tmpfile_stdout 09:38:06 INFO - Output received: 09:38:06 INFO - Usage: mozinstall [options] installer 09:38:06 INFO - Options: 09:38:06 INFO - -h, --help show this help message and exit 09:38:06 INFO - -d DEST, --destination=DEST 09:38:06 INFO - Directory to install application into. [default: 09:38:06 INFO - "/builds/slave/test"] 09:38:06 INFO - --app=APP Application being installed. [default: firefox] 09:38:06 INFO - mkdir: /builds/slave/test/build/application 09:38:06 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'] 09:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:38:24 INFO - Reading from file tmpfile_stdout 09:38:24 INFO - Output received: 09:38:24 INFO - /builds/slave/test/build/application/firefox/firefox 09:38:24 INFO - Running post-action listener: _resource_record_post_action 09:38:24 INFO - [mozharness: 2017-07-25 16:38:24.946605Z] Finished install step (success) 09:38:24 INFO - [mozharness: 2017-07-25 16:38:24.946861Z] Running setup-mitmproxy step. 09:38:24 INFO - Running pre-action listener: _resource_record_pre_action 09:38:24 INFO - Running main action method: setup_mitmproxy 09:38:24 INFO - Skipping: mitmproxy is not required 09:38:24 INFO - Running post-action listener: _resource_record_post_action 09:38:24 INFO - [mozharness: 2017-07-25 16:38:24.947991Z] Finished setup-mitmproxy step (success) 09:38:24 INFO - [mozharness: 2017-07-25 16:38:24.948214Z] Running run-tests step. 09:38:24 INFO - Running pre-action listener: _resource_record_pre_action 09:38:24 INFO - Running pre-action listener: _set_gcov_prefix 09:38:24 INFO - Running main action method: run_tests 09:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:38:24 INFO - Python 2.7.3 09:38:24 INFO - Return code: 0 09:38:24 INFO - grabbing minidump binary from tooltool 09:38: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')]} 09:38:24 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x27fdeb0>, '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 0x27fedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27ff240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:38:24 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 09:38:24 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:38:24 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 09:38:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:38:25 INFO - Return code: 0 09:38:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:38:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:38:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:38:25 INFO - ENV: RUST_BACKTRACE is now 1 09:38:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:38:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:38:25 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/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 09:38:25 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/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 09:38:25 INFO - Using env: {'DISPLAY': ':0', 09:38:25 INFO - 'HOME': '/home/cltbld', 09:38:25 INFO - 'LANG': 'en_US.UTF-8', 09:38:25 INFO - 'LANGUAGE': 'en_US:en', 09:38:25 INFO - 'LOGNAME': 'cltbld', 09:38:25 INFO - 'MAIL': '/var/mail/cltbld', 09:38:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:38:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:25 INFO - 'MOZ_NO_REMOTE': '1', 09:38:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:38:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:38:25 INFO - 'NO_EM_RESTART': '1', 09:38:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:25 INFO - 'PWD': '/builds/slave/test', 09:38:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:38:25 INFO - 'RUST_BACKTRACE': '1', 09:38:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:38:25 INFO - 'SHELL': '/bin/bash', 09:38:25 INFO - 'SHLVL': '1', 09:38:25 INFO - 'STYLO_FORCE_ENABLED': '1', 09:38:25 INFO - 'TERM': 'linux', 09:38:25 INFO - 'TMOUT': '86400', 09:38:25 INFO - 'USER': 'cltbld', 09:38:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491', 09:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:25 INFO - '_': '/tools/buildbot/bin/python'} 09:38:25 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/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 09:38:25 INFO - mozversion application_buildid: 20170725154251 09:38:25 INFO - mozversion application_changeset: 32d9d1e81cc607320a36391845917f645f7a7f72 09:38:25 INFO - mozversion application_display_name: Nightly 09:38:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:38:25 INFO - mozversion application_name: Firefox 09:38:25 INFO - mozversion application_remotingname: firefox 09:38:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:38:25 INFO - mozversion application_vendor: Mozilla 09:38:25 INFO - mozversion application_version: 56.0a1 09:38:25 INFO - mozversion platform_buildid: 20170725154251 09:38:25 INFO - mozversion platform_changeset: 32d9d1e81cc607320a36391845917f645f7a7f72 09:38:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:38:25 INFO - mozversion platform_version: 56.0a1 09:38:25 INFO - using testdate: 1501000705 09:38:25 INFO - actual date: 1501000705 09:38:25 INFO - starting webserver on 'localhost:38447' 09:38:25 INFO - SUITE-START | Running 2 tests 09:38:25 INFO - TEST-START | dromaeo_css 09:38:25 INFO - Initialising browser for dromaeo_css test... 09:38:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38447/getInfo.html -profile /tmp/tmpx0TIM8/profile 09:38:25 INFO - TEST-INFO | started process 3376 (/builds/slave/test/build/application/firefox/firefox http://localhost:38447/getInfo.html) 09:38:31 INFO - TEST-INFO | 3376: exit 0 09:38:31 INFO - Browser initialized. 09:38:31 INFO - Running cycle 1/1 for dromaeo_css test... 09:38:31 INFO - TEST-INFO | started process 3601 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx0TIM8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:32 INFO - PID 3601 | 09:38:32 INFO - PID 3601 | (/builds/slave/test/build/application/firefox/firefox:3656): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:38:32 INFO - PID 3601 | 09:38:42 INFO - PID 3601 | RSS: Main: 174669824 09:38:42 INFO - PID 3601 | 09:41:41 INFO - PID 3601 | Cycle 1(1): loaded http://localhost:38447/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38447/tests/dromaeo/cssquery-ext.html) 09:41:42 INFO - PID 3601 | RSS: Main: 175648768 09:41:42 INFO - PID 3601 | 09:44:44 INFO - PID 3601 | Cycle 1(1): loaded http://localhost:38447/tests/dromaeo/cssquery-ext.html (next: http://localhost:38447/tests/dromaeo/cssquery-jquery.html) 09:44:45 INFO - PID 3601 | RSS: Main: 173879296 09:44:45 INFO - PID 3601 | 09:47:46 INFO - PID 3601 | Cycle 1(1): loaded http://localhost:38447/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38447/tests/dromaeo/cssquery-mootools.html) 09:47:47 INFO - PID 3601 | RSS: Main: 175161344 09:47:47 INFO - PID 3601 | 09:50:49 INFO - PID 3601 | Cycle 1(1): loaded http://localhost:38447/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38447/tests/dromaeo/cssquery-prototype.html) 09:50:50 INFO - PID 3601 | RSS: Main: 173752320 09:50:50 INFO - PID 3601 | 09:53:54 INFO - PID 3601 | Cycle 1(1): loaded http://localhost:38447/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38447/tests/dromaeo/cssquery-yui.html) 09:53:54 INFO - PID 3601 | RSS: Main: 175259648 09:53:54 INFO - PID 3601 | 09:56:56 INFO - PID 3601 | Cycle 1(1): loaded http://localhost:38447/tests/dromaeo/cssquery-yui.html (next: http://localhost:38447/tests/dromaeo/cssquery-dojo.html) 09:56:56 INFO - PID 3601 | RSS: Main: 177954816 09:56:56 INFO - PID 3601 | 09:56:56 INFO - PID 3601 | __start_tp_report 09:56:56 INFO - PID 3601 | _x_x_mozilla_page_load 09:56:56 INFO - PID 3601 | _x_x_mozilla_page_load_details 09:56:56 INFO - PID 3601 | |i|pagename|runs| 09:56:56 INFO - PID 3601 | |0;dojo.html;3112.59536260286;3121.2040929562963;3135.874565017399;3143.5913331266934;3186.2353035271526;6043.274807023158;6049.395060493954;6218.875622487553;6223.657698826562;6226.813195604128;5989.4010598940085;6075.574709770307;6100.450959413652;6101.78643747469;6167.352427995063;5664;5666.603337766358;5726.770929162849;5732.0828667413225;5769.740361683735;527.9100629334081;5581.916271255935;5646.915296270559;5771.971140144294;5788.681622510781;5563.944360556383;5606.719664016824;5632.07070833312;5693.943060569424;5698.487136157745;5626.156076588503;5659.179418984266;5707.800226992035;5714.371419143891;5751.798687045934;5574.721263936827;5622.690752008638;5659.830205093854;5669.518090962273;5708.058170401879;5997.070454079625;6095.603785753912;6132.938670613281;6184.041473571604;6195.473384762301;6406.903896441557;6520.0871877937125;6532.085508028832;6538.869222615521;6684.766033188864;6494.480441564663;6582;6675.732970681167;6689.799306020827;6707.966460167668;6190.50475961922;6191.195144631214;6208.099825525273;6274.435300822948;6285.717142728588;6848.075509806212;6861.588304701734;6921.688524016431;6939.201991771011;6948.131483564554;6342.841428964312;6401.711922963478;6441.806745797634;6511.479081673455;6611;6580.506462015368;6606.570572912745;6613.900791488131;6664.333566643297;6666.366695163952;6538.934610653928;6547.476201903836;6650.567713098633;6659.6670166491485;6665.833354166185;6337.556371053982;6452.9677351612945;6588.40704336612;6684.064231007663;6693.297203793629;5210.322658054429;5222.242774797709;5223.791048358099;5230.110881150214;5246.580273578104;4526.298423744325;4534.727916325031;4550.681452298308;4560.429946256718;4586.518415566317;5546.639468434539;5619.8595035124445;5624.240727501735;5624.521915637135;5643.097104463266;6510;6521.902171467432;6528.836779080561;6531.575447595891;6538.313477084933;5607;5622.212890195376;5624.943750562524;5639.689817060019;5644.266245388156;5302.602304827154;5757.971210143923;5853.414658534198;5865.560082993792;5875.383084776037;6392.520560957947;6400.839979000562;6424.068510066014;6479.060536222221;6488.610683359014;6457.321981191907;6528.183977002875;6585.275619681927;6637.502187336066;6673.9332606673315;6362.936370636235;6378.138951241523;6403.775867844603;6413.8717225656155;6429.9678501607195;6433.388828061326;6466.191726034246;6469.579477334147;6477.255115661571;6479.611223326616;6562.8359291018105;6594.439472644879;6617.569857959217;6618.933810661832;6648.734050638114;6435.967820160869;6453.677316134081;6474.741010359533;6475.611463312216;6545.5090868184125;6267.122602835516;6367.458766004941;6432.774852880127;6509.218893732783;6518;6316.273628532777;6387.457066149429;6396.616203027833;6402.199725034371;6409;6472;6488;6538.378854009052;6567.507436942344;6571.34286571339;6250.718717657625;6263.404976527223;6299.181106456132;6358.268799088164;6365.045243213555 09:56:56 INFO - PID 3601 | |1;ext.html;817.1991448380587;829.7676650537851;851.7019043334833;890.3189060368815;893.1024320557839;40131;40952;41006.58993410065;41017.97455063629;41181.38227926576;37030.8148459258;37238.25523489529;37350;37369.81315093428;37413.06467338318;6712.832179195522;6837.418819400357;6861.519693621449;6864.24493305736;6876;6806.3874251317375;6870.484713646471;6894.9655251723925;6895.413889819371;6910.723571057151;6807.353301436356;6877.931220687804;6885;6891.9310806892045;6894.448444124458;6813.761518346834;6825.624590647512;6830.6243156626615;6835.282295358966;6860.039594456755;6213.813585592439;6237.625742455467;6262.467690246334;6264.1543391642;6281.057841323793;33146.33707325864;33470.665293346996;33562.83218583916;33659.495107573406;33690.83154584236;3379.932401351959;3411.812350320731;3414.078198886287;3415.9487607685905;3425.72594192464;27690.307742306402;27695.169144925683;27721.306967325778;27742.167734967985;27767.444651107067;4241.215375155606;4248.98024474127;4267.573242675739;4326.372675961999;4363.170997510463;187789;190184;190829;191724;191820;47064.5293547067;48088.75955620199;48093.75953120212;48277.5172248273;48278.517214828105;49492.75253623709;49538.75230623824;49722.75138624284;49827;49853.25220121701;16031.519054428385;16074.75887861683;16229.513114606581;16232.188390580424;16403.58991025234;13669;13723.725525489433;13737.587872363845;13740.244286564135;13751;12809.103362764703;12987.675308117183;13009.674758131123;13053.216806991612;13253.66865828362;5551.72241387929;5582.888342233133;5683.744231509592;5686.488216060575;5691.573132015115;952.4237836109054;964.2575217082807;975.5073687787622;1008.5360734062267;1009;21649.242276520563;21649.89175054115;21938.451538711342;22014.559708805733;22205;1079.109734469066;1098.4288170151476;1098.6374496416163;1107.2249425402251;1108.4734750993214;11505.309681419141;11584.189106762615;11840.467178976967;16107.194640268173;16465.765067619977;212681;216005;229610;230672;231742;55086;55372.446275537535;55581;55599;55707.72146139244;1440.071154105596;1469.3975470057226;1477.5593796048765;1483.807105076333;1485.5172069077378;25883.741162588885;26370.736292637597;26432.867835660698;26514.86742566275;26580;36011.27977440384;36411.27177456489;36432.81783591065;36484.63515364812;36493.27013459663;190157;191228;192561;193146;194879;32602.83698581492;35625.64374356327;36333.81833090818;36385.63614363822;36426.27147456983;44567.77716111398;46156.769216153705;46281.305780412644;46291.76854115708;46323.768381157875;32762.344753104328;33263.83368083144;33931.83034084814;33997.66002339945;34433.82783086068;32846.029238245785;33502.162445939044;33798.32403351968;33923;34498;36495.08762280964;36857.44713829241;36994.8150259247;37024;37495.25009499849;31030.53454198234;32633;32660.673393265763;33161.83419082889;33354;23967.28098157058;24486.38784030342;24492.632610511213;24659.630105548073;25002 09:56:56 INFO - PID 3601 | |2;jquery.html;573.3463851209619;583.9562032847534;590.3653572409657;609.3388673289483;614.2966303582398;21618.675719864215;22289.108435662554;22425;22511.77488225117;22534.098636054558;24691.259262222164;25468.872655636744;26663.600045999276;26768.19695409136;26780.866095669495;6456.644884531361;6560.147180866481;6601.910684737013;6606.14120164378;6608.735650573972;5856.414358564152;6405.135306733578;6468.191476065492;6483;6488.221413430395;6401.071844582532;6403.5197360197935;6417.358264173568;6425.261094974067;6437.485001199892;6392.232932048138;6395.136656551353;6423.421892029716;6424.03639454081;6428.260750013762;5985.850353741192;5987.1318658794235;5990.520758339366;6002.729877155539;6005.729742161613;28267.85866070677;29155.70844291551;29632.407351853057;29699.554506682416;30703.539446908315;1897.6584214841323;1958.7551556055494;1963.3128405058258;1964.4401345616432;1966.1742068331191;18621;19354.419367419;19373.612527749367;19385.51536211592;19404.708929366072;2344.2029709898547;2352.1179557665873;2376.9524609507885;2423.8060955123547;2434.902603895842;140731;143008;143179;143588;143970;14986.925065374822;15096.39614415411;15199.31603077842;15443.768343474856;15659.843401565839;21400.892995535232;22558.77441225567;22590.661140082913;22967.655485167736;24194.758052419602;10688.412137332365;10693.19801014927;10706.1435085193;10835.783284334428;10920.344779313107;9951.452670103068;10050.69847904564;10052.899471005343;10112.443815590213;10144.746381340377;9150.908490915006;9163.312751543528;9186.63253469867;9433.292503062297;9499.145076943109;2722.088100486359;2757.4347258811895;2822.223888430657;2824.3927555575237;2887.2926133097526;935.6444551070549;961.6105477281712;969.7333233360882;990.2127808392345;1005.8483036922829;19209.423717288224;19471.707924381146;19876.204951801767;23447.41381465477;25284;1021.4637315409469;1030.8714691161126;1051.2090399987892;1073.0932362153967;1075.349413604758;10696.304740191863;11057;11343.829842552368;13015.934920325337;13791.448342066205;502349;514249;518803;519452;521440;125813;128108;128667.35666321796;129836;132912;1269.9873001270055;1281.9487220511262;1312.967175820612;1322.7288405876773;1327.024636891903;20538.38384848457;21665.025073872028;22379;22509.88745056264;22775.31674049715;45139.32291015703;58508.70745646244;58820;60527.78944421175;62367.81712640036;78674.2132578667;81409.59295203486;81463;82101.58949205215;82401.58799205965;19373.709394358815;21777.455563611038;21912.671309930683;22090.77909220887;22211.222607209318;24397.780110994736;24551.75448245566;24621.38446538851;24633.507329853663;24636.753632463446;18648.906755466134;19161;19272.518187044876;19307;19521.902390487958;16384.590385240463;17015.91492042532;17243.3964811231;17450.91274543619;18000.279988800303;19687.40937771869;19744.30894918671;19745.30891418851;19835.206591736747;19943.301984430476;20389;20783.896080519502;21671;21824.236151734614;21834.781652183276;17904.194311255764;19338.516537086685;19395;19482.4155275342;19667.70498442496 09:56:56 INFO - PID 3601 | |3;mootools.html;1342.6978929740806;1405.4799724102083;1415.4126037694357;1427.4504315838399;1443.9711205775884;13567.728645427085;14168.291585420739;14181.645458863533;14193.929030354833;14196.929015354921;13691.589252322421;14193.290335483236;14716.705665886702;14737.48418805342;14804.6298842529;2741.7121202273725;2798.6921438641734;2799.3981294021783;2799.692033876272;2801.229661842999;2610.164747280871;2652.5888487284406;2719.578465337866;2720.2111387697546;2720.415110751188;2717.605947137664;2719.075514325129;2746.8626568671584;2748.367875388662;2752.8898844046216;2674.291312802099;2674.772644325225;2676.558367869299;2684.2886635041727;2696.164189101365;2586.366340246652;2595.2992691973063;2623.7507436793476;2625.3567875870344;2626.500964816679;13493.325333733274;13799.793003104962;13864.376103075387;13881.167129972133;13881.652958676013;7399.741009064711;7643.541387516767;7645.273698998586;7658.234176582353;7684.654190561382;4856.587190088847;5059.747012649353;5126.743662816844;5138.383393992707;5149.253358263031;1756.5345183526376;1829.8444632206279;1866.934657287002;1886.5377982394402;1890.8014658460802;23431.88284058586;23459.76540234593;23554.175603853953;23725.288241352788;23750.287491375286;6483.222013358333;6496.350364963466;6505.934940650533;6658.400743933069;6703.497237707192;15820.446284379992;15861.841381586268;15943.681126377407;15943.681126377407;15968.441104561285;7395.519291246052;7557.773266802004;7559.092908850973;7560.130584982798;7565.130010048805;6828.385445309847;6852.862942741117;6899.17209934801;6969.163700355888;7067.151941767021;4599.655025873073;4637.165310244189;4971.403431588233;5138.2806407103035;5184;1482.4885414531968;1506.1941861104256;1508.7058023685495;1515.9242037898061;1520.118331367804;829.7884039569992;837.9874301885354;855.6449073634373;871.4466313890725;896.8699538567001;8115.79710507242;8168.2240187183315;8299.336053115736;8320.417570769989;8344.207300306462;952.9231967876256;971.3783178765595;999.9950050199503;1003.448103543063;1005.2209537608412;4152.47315683581;5068.41713202979;5189.195674670432;5208.062548741263;5340.359156901197;18394.26422943094;18740.25038998452;20214.595708085755;20220.79779202189;20227.089780959894;18455.630887382176;18797.248110075718;18953.526161845788;18981.62036759257;19026;9647.614095436244;10225.948870255601;10349.430781307101;10386.688399348031;10552;7967.800804979922;7971.521708697497;8344.87482687748;8371.41400101987;8479.915200847912;16665.666686666442;17264.050477223875;17328.22023008993;17406.651866962336;17580.296788128842;16842.66314673675;17230.13849307555;17353;17536.561585959942;17748.467545973643;11473.885261147281;11765.176437649283;11837.053035756946;11837.704057398634;11840.467178977138;11439.199256051996;11776;12288.385580721108;13163.078584498993;13181.670458238237;9751.902480975099;9834.262430317898;10460;10560.947195263974;10564.577416903545;10260.230482713976;10804.891951080388;10842.24104312722;10857.945710271399;10962.506687198937;9100.726978190665;9360.625574976924;9479.431234125974;9529.285303602397;9559.426434413957;6190.380961903774;6430.807075787734;6524.380183882524;6527.445167160845;6694.397504224643;6681.532292739462;6709.496787741036;7094.822629434099;7179.497435179487;7291.781246562611 09:56:56 INFO - PID 3601 | |4;prototype.html;497.04567230916626;504.8889244366238;525.7949399734104;526.628726747643;531.4924247343787;5992.460678538931;6255.499560035192;6273.56085074045;6280.770326534083;6282.246130464345;5999.940000599992;6027.698615069251;6030.065339872316;6040.033594624861;6044.153818465418;4877.073356062355;4897.338859254009;4935.851924442263;4956.479569645189;4980.1782705853675;4783.545563171493;4922.55696987271;4947.579455746266;4950.975245123769;4975.104481193383;4820.614350851941;4909.926351104718;4915.926261106087;4916.877078073059;4936.654434189608;4826.1795494765975;4851.587615052725;4870.732109733964;4905.460399356068;4943.802247910079;4352.216601011816;4365.2142614329405;4449.443819522559;4456.331550267453;4494.8202071917085;5942.138389933479;6017.819465416044;6035.124906888476;6098.54260930432;6101.450869421729;5563.499285064323;5604.1593760935775;5630.690312032836;5679.403662615448;5748.310202775694;6480;6496.545241833073;6496.9675151624415;6518.348165183444;6519.902201466982;4790.856274311776;4805.447373552051;4851.805927762885;4873.805047798084;4897.363342765418;6574.276829548746;6660.4708798016845;6761.256261811197;6816.318368163194;6927.445804335641;4938.234573641092;4940.728259945666;4944.678595891256;4961.801527938915;4962.9751851241235;5015.799368025238;5033.597312215014;5055.393352797614;5055.873603159876;5116.590672746172;5528.502434780889;5584.720763961785;5635.436456354331;5636.2954630671165;5651.519620832275;6187.535934804908;6427.260865000491;6496.967515162489;6566.244881838554;6580.276169621337;1671.7743104680956;1680.5378520906847;1697.3634886917405;1728.3691452619703;1752.7458518514743;1038.600138946496;1193.6240084373394;1240.3550153919912;1242.944067516964;1244.8070549064728;5502.202180683778;5593;5624.268845050117;5639.436056394327;5668.149777533403;6652;6666.333366663295;6668.766593169313;6687.692746002718;6709.463242940651;5625;5683.602147849693;5701.00232459318;5705;5747.195392645033;3493.615702272748;3529.382358087376;3532.6643968822923;3536.734744894143;3542.6811586957297;6359.583358323807;6451.387118223761;6463.6768161591735;6472.838179045561;6488.351164883474;6659.733610655617;6747.5371489407;6815.761448349284;6848.794536163923;6880.483963702743;6409;6409.551331406757;6417.903731444126;6418.26189988148;6447.516436267299;6542.378474044958;6618.867622647616;6622.768203112868;6672.566283191577;6708.26209116987;6575.243846957661;6589.769358072444;6594.670266486561;6634.535582509178;6638.402543771083;6364.745410183541;6370.235571731422;6410.358964103552;6416.390442907917;6545.21457425112;5005.399352077774;5031.371078615173;5044.33536932804;5045.2936588876855;5053.69677819332;6607.867842643216;6620.602763834186;6647.767328143492;6676.732930682912;6707.262201157966;6255.186825712628;6415.775447859302;6457.3865482779065;6474.539674923499;6507.609543427409;6342.23893132827;6418.293987661447;6444.2911279758255;6484;6556.409923106943;6308.400701933309;6374.681265936591;6388.840278993062;6418.454431373386;6431.389018043278;6525.813852359513;6530.249021362414;6564.737410503526;6582.868342633216;6629.574641452111;6012.849678758066;6240.095186198067;6288.591241569284;6363.331850155667;6376.872462550816 09:56:56 INFO - PID 3601 | |5;yui.html;895.0467751844286;896.2919293757932;909.858971859362;924.0205382294768;925.8148370325937;17794.466166015023;18957.715634265496;19025.2389904404;19094.33169839056;19098.522536936565;16570.83429165708;16872.746908796376;16935.49193524193;19225;21316.253931112413;1053.8893416191286;1071.132382769959;1073.7745073534568;1073.7798751256014;1075.091547642245;1055.5513906589706;1059.729768908927;1060.2313322840916;1060.4697651174413;1061.2306078093359;1008.9115530851141;1095.5782023920808;1098.1434481104752;1104.320842681753;1118.429600903541;1083.6965649618119;1085.511519816082;1086.4241951765498;1087.1846115413439;1087.3801932898252;789.2856964447185;793.611130546034;793.714262865368;798.0662624729081;798.2735710503472;18532.351367702202;18668.906655466773;18892.622147556973;18901.527461813428;20129.496762580908;19853.205871765113;20680.689789653166;21023;21259.787402125934;21273.893630531747;15266.923665381713;15631.843681563267;15726.921365393215;15804.130772807492;15829.841701582953;3557.0395993081725;3582.7492075554724;3588.067102553329;3591.2278860044953;3600.945985810215;116544.41727791392;122328;122381;125981;129393;16878.987260764392;17032.57418564521;17159.57101072483;17254.741178882075;17325.480235592953;29515.262118446783;30599.541006884916;30886.382272354876;31063.689363106532;31139.221519461742;8438.156184381636;8793.560321984003;8811.69159079442;8841;8843.380963332633;4100.0159961609;4169.249535083653;4171.290880550314;4184.602462766033;4202.516710578261;5274.92087618686;5290.1800220965815;5395.433479484597;5410.648307859977;5413.864653383618;1065.3767545985531;1074.4735079810837;1081.4214395298477;1081.5133190064346;1082.2910993299402;639.7696829141505;661.3683931845172;679.1917618034523;685.766839274649;707.2785758526275;11737.295762254293;12349.135560510678;13802.447902084004;13876;14145.080569762933;591.9526437884958;632.6646877155115;645.2740666749906;664.465105589995;703.672792151652;4979.875503112378;5392.379876314274;5865.090910908816;5995.670238136944;6182.1345011698395;105497;109793;110729.89270107356;111295.44352278186;112225;89329;93455;94359;97294;97876;77145.61427192828;80192.19807801965;80208;81498.59250703827;82086;12478.18891772032;12974.481020759442;13001.539353471753;13189.472421103432;13246.741559551827;35504.64495355013;37167.62832371642;37781.622183777814;37892.24215515729;38048.80975595104;64674.27675481302;64952.00922381191;67050.66474667596;67188.99216511658;69098;18808.811911880704;18997.90501047486;19291.807081929;19450.319238826574;19501;20475.8976205118;20734.481637959172;20998.58002839965;21130.78869211288;21278.574428511653;18298;18368.540786480444;18528.629427411106;18987.715184271972;19328;18845.340413086025;19857.60284794267;19979.20083196714;20010.799892001476;20147.496312592302;17987.370442034466;18442.446726598224;18912.621747564695;19107.426777196706;19174.904125479283;12805.679858003625;12928.612141635766;13559.118657287245;13723.931380343434;13732.45070197221;13462.730745385234;13827.79258311106;14096.647583810487;14706.485273015393;14930.925345373204 09:56:56 INFO - PID 3601 | __end_tp_report 09:56:56 INFO - PID 3601 | __start_cc_report 09:56:56 INFO - PID 3601 | _x_x_mozilla_cycle_collect,366 09:56:56 INFO - PID 3601 | __end_cc_report 09:56:56 INFO - PID 3601 | __startTimestamp1501001816871__endTimestamp 09:56:56 INFO - PID 3601 | 09:56:56 INFO - PID 3601 | ------- Summary: start ------- 09:56:56 INFO - PID 3601 | Number of tests: 6 09:56:56 INFO - PID 3601 | 09:56:56 INFO - PID 3601 | [#0] dojo.html Cycles:165 Average:6038.74 Median:6358.27 stddev:840.82 (13.2%) stddev-sans-first:811.46 09:56:56 INFO - PID 3601 | Values: 3112.6 3121.2 3135.9 3143.6 3186.2 6043.3 6049.4 6218.9 6223.7 6226.8 5989.4 6075.6 6100.5 6101.8 6167.4 5664.0 5666.6 5726.8 5732.1 5769.7 527.9 5581.9 5646.9 5772.0 5788.7 5563.9 5606.7 5632.1 5693.9 5698.5 5626.2 5659.2 5707.8 5714.4 5751.8 5574.7 5622.7 5659.8 5669.5 5708.1 5997.1 6095.6 6132.9 6184.0 6195.5 6406.9 6520.1 6532.1 6538.9 6684.8 6494.5 6582.0 6675.7 6689.8 6708.0 6190.5 6191.2 6208.1 6274.4 6285.7 6848.1 6861.6 6921.7 6939.2 6948.1 6342.8 6401.7 6441.8 6511.5 6611.0 6580.5 6606.6 6613.9 6664.3 6666.4 6538.9 6547.5 6650.6 6659.7 6665.8 6337.6 6453.0 6588.4 6684.1 6693.3 5210.3 5222.2 5223.8 5230.1 5246.6 4526.3 4534.7 4550.7 4560.4 4586.5 5546.6 5619.9 5624.2 5624.5 5643.1 6510.0 6521.9 6528.8 6531.6 6538.3 5607.0 5622.2 5624.9 5639.7 5644.3 5302.6 5758.0 5853.4 5865.6 5875.4 6392.5 6400.8 6424.1 6479.1 6488.6 6457.3 6528.2 6585.3 6637.5 6673.9 6362.9 6378.1 6403.8 6413.9 6430.0 6433.4 6466.2 6469.6 6477.3 6479.6 6562.8 6594.4 6617.6 6618.9 6648.7 6436.0 6453.7 6474.7 6475.6 6545.5 6267.1 6367.5 6432.8 6509.2 6518.0 6316.3 6387.5 6396.6 6402.2 6409.0 6472.0 6488.0 6538.4 6567.5 6571.3 6250.7 6263.4 6299.2 6358.3 6365.0 09:56:56 INFO - PID 3601 | 09:56:56 INFO - PID 3601 | [#1] ext.html Cycles:180 Average:37078.00 Median:18929.78 stddev:52634.06 (278.0%) stddev-sans-first:52711.29 09:56:56 INFO - PID 3601 | Values: 817.2 829.8 851.7 890.3 893.1 40131.0 40952.0 41006.6 41018.0 41181.4 37030.8 37238.3 37350.0 37369.8 37413.1 6712.8 6837.4 6861.5 6864.2 6876.0 6806.4 6870.5 6895.0 6895.4 6910.7 6807.4 6877.9 6885.0 6891.9 6894.4 6813.8 6825.6 6830.6 6835.3 6860.0 6213.8 6237.6 6262.5 6264.2 6281.1 33146.3 33470.7 33562.8 33659.5 33690.8 3379.9 3411.8 3414.1 3415.9 3425.7 27690.3 27695.2 27721.3 27742.2 27767.4 4241.2 4249.0 4267.6 4326.4 4363.2 187789.0 190184.0 190829.0 191724.0 191820.0 47064.5 48088.8 48093.8 48277.5 48278.5 49492.8 49538.8 49722.8 49827.0 49853.3 16031.5 16074.8 16229.5 16232.2 16403.6 13669.0 13723.7 13737.6 13740.2 13751.0 12809.1 12987.7 13009.7 13053.2 13253.7 5551.7 5582.9 5683.7 5686.5 5691.6 952.4 964.3 975.5 1008.5 1009.0 21649.2 21649.9 21938.5 22014.6 22205.0 1079.1 1098.4 1098.6 1107.2 1108.5 11505.3 11584.2 11840.5 16107.2 16465.8 212681.0 216005.0 229610.0 230672.0 231742.0 55086.0 55372.4 55581.0 55599.0 55707.7 1440.1 1469.4 1477.6 1483.8 1485.5 25883.7 26370.7 26432.9 26514.9 26580.0 36011.3 36411.3 36432.8 36484.6 36493.3 190157.0 191228.0 192561.0 193146.0 194879.0 32602.8 35625.6 36333.8 36385.6 36426.3 44567.8 46156.8 46281.3 46291.8 46323.8 32762.3 33263.8 33931.8 33997.7 34433.8 32846.0 33502.2 33798.3 33923.0 34498.0 36495.1 36857.4 36994.8 37024.0 37495.3 31030.5 32633.0 32660.7 33161.8 33354.0 23967.3 24486.4 24492.6 24659.6 25002.0 09:56:56 INFO - PID 3601 | 09:56:56 INFO - PID 3601 | [#2] jquery.html Cycles:180 Average:36989.05 Median:22510.83 stddev:87003.73 (386.5%) stddev-sans-first:87204.84 09:56:56 INFO - PID 3601 | Values: 573.3 584.0 590.4 609.3 614.3 21618.7 22289.1 22425.0 22511.8 22534.1 24691.3 25468.9 26663.6 26768.2 26780.9 6456.6 6560.1 6601.9 6606.1 6608.7 5856.4 6405.1 6468.2 6483.0 6488.2 6401.1 6403.5 6417.4 6425.3 6437.5 6392.2 6395.1 6423.4 6424.0 6428.3 5985.9 5987.1 5990.5 6002.7 6005.7 28267.9 29155.7 29632.4 29699.6 30703.5 1897.7 1958.8 1963.3 1964.4 1966.2 18621.0 19354.4 19373.6 19385.5 19404.7 2344.2 2352.1 2377.0 2423.8 2434.9 140731.0 143008.0 143179.0 143588.0 143970.0 14986.9 15096.4 15199.3 15443.8 15659.8 21400.9 22558.8 22590.7 22967.7 24194.8 10688.4 10693.2 10706.1 10835.8 10920.3 9951.5 10050.7 10052.9 10112.4 10144.7 9150.9 9163.3 9186.6 9433.3 9499.1 2722.1 2757.4 2822.2 2824.4 2887.3 935.6 961.6 969.7 990.2 1005.8 19209.4 19471.7 19876.2 23447.4 25284.0 1021.5 1030.9 1051.2 1073.1 1075.3 10696.3 11057.0 11343.8 13015.9 13791.4 502349.0 514249.0 518803.0 519452.0 521440.0 125813.0 128108.0 128667.4 129836.0 132912.0 1270.0 1281.9 1313.0 1322.7 1327.0 20538.4 21665.0 22379.0 22509.9 22775.3 45139.3 58508.7 58820.0 60527.8 62367.8 78674.2 81409.6 81463.0 82101.6 82401.6 19373.7 21777.5 21912.7 22090.8 22211.2 24397.8 24551.8 24621.4 24633.5 24636.8 18648.9 19161.0 19272.5 19307.0 19521.9 16384.6 17015.9 17243.4 17450.9 18000.3 19687.4 19744.3 19745.3 19835.2 19943.3 20389.0 20783.9 21671.0 21824.2 21834.8 17904.2 19338.5 19395.0 19482.4 19667.7 09:56:56 INFO - PID 3601 | 09:56:56 INFO - PID 3601 | [#3] mootools.html Cycles:180 Average:8698.27 Median:2663.44 stddev:5972.26 (224.2%) stddev-sans-first:5963.44 09:56:56 INFO - PID 3601 | Values: 1342.7 1405.5 1415.4 1427.5 1444.0 13567.7 14168.3 14181.6 14193.9 14196.9 13691.6 14193.3 14716.7 14737.5 14804.6 2741.7 2798.7 2799.4 2799.7 2801.2 2610.2 2652.6 2719.6 2720.2 2720.4 2717.6 2719.1 2746.9 2748.4 2752.9 2674.3 2674.8 2676.6 2684.3 2696.2 2586.4 2595.3 2623.8 2625.4 2626.5 13493.3 13799.8 13864.4 13881.2 13881.7 7399.7 7643.5 7645.3 7658.2 7684.7 4856.6 5059.7 5126.7 5138.4 5149.3 1756.5 1829.8 1866.9 1886.5 1890.8 23431.9 23459.8 23554.2 23725.3 23750.3 6483.2 6496.4 6505.9 6658.4 6703.5 15820.4 15861.8 15943.7 15943.7 15968.4 7395.5 7557.8 7559.1 7560.1 7565.1 6828.4 6852.9 6899.2 6969.2 7067.2 4599.7 4637.2 4971.4 5138.3 5184.0 1482.5 1506.2 1508.7 1515.9 1520.1 829.8 838.0 855.6 871.4 896.9 8115.8 8168.2 8299.3 8320.4 8344.2 952.9 971.4 1000.0 1003.4 1005.2 4152.5 5068.4 5189.2 5208.1 5340.4 18394.3 18740.3 20214.6 20220.8 20227.1 18455.6 18797.2 18953.5 18981.6 19026.0 9647.6 10225.9 10349.4 10386.7 10552.0 7967.8 7971.5 8344.9 8371.4 8479.9 16665.7 17264.1 17328.2 17406.7 17580.3 16842.7 17230.1 17353.0 17536.6 17748.5 11473.9 11765.2 11837.1 11837.7 11840.5 11439.2 11776.0 12288.4 13163.1 13181.7 9751.9 9834.3 10460.0 10560.9 10564.6 10260.2 10804.9 10842.2 10857.9 10962.5 9100.7 9360.6 9479.4 9529.3 9559.4 6190.4 6430.8 6524.4 6527.4 6694.4 6681.5 6709.5 7094.8 7179.5 7291.8 09:56:56 INFO - PID 3601 | 09:56:56 INFO - PID 3601 | [#4] prototype.html Cycles:180 Average:5448.30 Median:6028.88 stddev:1529.82 (25.4%) stddev-sans-first:1488.29 09:56:56 INFO - PID 3601 | Values: 497.0 504.9 525.8 526.6 531.5 5992.5 6255.5 6273.6 6280.8 6282.2 5999.9 6027.7 6030.1 6040.0 6044.2 4877.1 4897.3 4935.9 4956.5 4980.2 4783.5 4922.6 4947.6 4951.0 4975.1 4820.6 4909.9 4915.9 4916.9 4936.7 4826.2 4851.6 4870.7 4905.5 4943.8 4352.2 4365.2 4449.4 4456.3 4494.8 5942.1 6017.8 6035.1 6098.5 6101.5 5563.5 5604.2 5630.7 5679.4 5748.3 6480.0 6496.5 6497.0 6518.3 6519.9 4790.9 4805.4 4851.8 4873.8 4897.4 6574.3 6660.5 6761.3 6816.3 6927.4 4938.2 4940.7 4944.7 4961.8 4963.0 5015.8 5033.6 5055.4 5055.9 5116.6 5528.5 5584.7 5635.4 5636.3 5651.5 6187.5 6427.3 6497.0 6566.2 6580.3 1671.8 1680.5 1697.4 1728.4 1752.7 1038.6 1193.6 1240.4 1242.9 1244.8 5502.2 5593.0 5624.3 5639.4 5668.1 6652.0 6666.3 6668.8 6687.7 6709.5 5625.0 5683.6 5701.0 5705.0 5747.2 3493.6 3529.4 3532.7 3536.7 3542.7 6359.6 6451.4 6463.7 6472.8 6488.4 6659.7 6747.5 6815.8 6848.8 6880.5 6409.0 6409.6 6417.9 6418.3 6447.5 6542.4 6618.9 6622.8 6672.6 6708.3 6575.2 6589.8 6594.7 6634.5 6638.4 6364.7 6370.2 6410.4 6416.4 6545.2 5005.4 5031.4 5044.3 5045.3 5053.7 6607.9 6620.6 6647.8 6676.7 6707.3 6255.2 6415.8 6457.4 6474.5 6507.6 6342.2 6418.3 6444.3 6484.0 6556.4 6308.4 6374.7 6388.8 6418.5 6431.4 6525.8 6530.2 6564.7 6582.9 6629.6 6012.8 6240.1 6288.6 6363.3 6376.9 09:56:56 INFO - PID 3601 | 09:56:56 INFO - PID 3601 | [#5] yui.html Cycles:180 Average:23306.15 Median:19309.90 stddev:31141.03 (161.3%) stddev-sans-first:31182.92 09:56:56 INFO - PID 3601 | Values: 895.0 896.3 909.9 924.0 925.8 17794.5 18957.7 19025.2 19094.3 19098.5 16570.8 16872.7 16935.5 19225.0 21316.3 1053.9 1071.1 1073.8 1073.8 1075.1 1055.6 1059.7 1060.2 1060.5 1061.2 1008.9 1095.6 1098.1 1104.3 1118.4 1083.7 1085.5 1086.4 1087.2 1087.4 789.3 793.6 793.7 798.1 798.3 18532.4 18668.9 18892.6 18901.5 20129.5 19853.2 20680.7 21023.0 21259.8 21273.9 15266.9 15631.8 15726.9 15804.1 15829.8 3557.0 3582.7 3588.1 3591.2 3600.9 116544.4 122328.0 122381.0 125981.0 129393.0 16879.0 17032.6 17159.6 17254.7 17325.5 29515.3 30599.5 30886.4 31063.7 31139.2 8438.2 8793.6 8811.7 8841.0 8843.4 4100.0 4169.2 4171.3 4184.6 4202.5 5274.9 5290.2 5395.4 5410.6 5413.9 1065.4 1074.5 1081.4 1081.5 1082.3 639.8 661.4 679.2 685.8 707.3 11737.3 12349.1 13802.4 13876.0 14145.1 592.0 632.7 645.3 664.5 703.7 4979.9 5392.4 5865.1 5995.7 6182.1 105497.0 109793.0 110729.9 111295.4 112225.0 89329.0 93455.0 94359.0 97294.0 97876.0 77145.6 80192.2 80208.0 81498.6 82086.0 12478.2 12974.5 13001.5 13189.5 13246.7 35504.6 37167.6 37781.6 37892.2 38048.8 64674.3 64952.0 67050.7 67189.0 69098.0 18808.8 18997.9 19291.8 19450.3 19501.0 20475.9 20734.5 20998.6 21130.8 21278.6 18298.0 18368.5 18528.6 18987.7 19328.0 18845.3 19857.6 19979.2 20010.8 20147.5 17987.4 18442.4 18912.6 19107.4 19174.9 12805.7 12928.6 13559.1 13723.9 13732.5 13462.7 13827.8 14096.6 14706.5 14930.9 09:56:56 INFO - PID 3601 | -------- Summary: end -------- 09:56:56 INFO - PID 3601 | 09:56:57 INFO - TEST-INFO | 3601: exit 0 09:56:57 INFO - TEST-OK | dromaeo_css | took 1112240ms 09:56:57 INFO - TEST-START | kraken 09:56:57 INFO - Initialising browser for kraken test... 09:56:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38447/getInfo.html -profile /tmp/tmpsxiT6P/profile 09:56:57 INFO - TEST-INFO | started process 3973 (/builds/slave/test/build/application/firefox/firefox http://localhost:38447/getInfo.html) 09:57:02 INFO - TEST-INFO | 3973: exit 0 09:57:02 INFO - Browser initialized. 09:57:02 INFO - Running cycle 1/1 for kraken test... 09:57:02 INFO - TEST-INFO | started process 4201 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsxiT6P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:03 INFO - PID 4201 | 09:57:03 INFO - PID 4201 | (/builds/slave/test/build/application/firefox/firefox:4255): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 09:57:03 INFO - PID 4201 | 09:57:14 INFO - PID 4201 | RSS: Main: 174211072 09:57:14 INFO - PID 4201 | 09:57:16 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38447/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:17 INFO - PID 4201 | RSS: Main: 178753536 09:57:17 INFO - PID 4201 | 09:57:20 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38447/tests/kraken/driver.html?testName=audio-dft) 09:57:21 INFO - PID 4201 | RSS: Main: 179191808 09:57:21 INFO - PID 4201 | 09:57:24 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38447/tests/kraken/driver.html?testName=audio-fft) 09:57:24 INFO - PID 4201 | RSS: Main: 173506560 09:57:24 INFO - PID 4201 | 09:57:26 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38447/tests/kraken/driver.html?testName=audio-oscillator) 09:57:27 INFO - PID 4201 | RSS: Main: 174637056 09:57:27 INFO - PID 4201 | 09:57:29 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38447/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:57:30 INFO - PID 4201 | RSS: Main: 174637056 09:57:30 INFO - PID 4201 | 09:57:33 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38447/tests/kraken/driver.html?testName=imaging-darkroom) 09:57:34 INFO - PID 4201 | RSS: Main: 177414144 09:57:34 INFO - PID 4201 | 09:57:37 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38447/tests/kraken/driver.html?testName=imaging-desaturate) 09:57:38 INFO - PID 4201 | RSS: Main: 177385472 09:57:38 INFO - PID 4201 | 09:57:41 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38447/tests/kraken/driver.html?testName=json-parse-financial) 09:57:41 INFO - PID 4201 | RSS: Main: 178327552 09:57:41 INFO - PID 4201 | 09:57:43 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38447/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:57:44 INFO - PID 4201 | RSS: Main: 178491392 09:57:44 INFO - PID 4201 | 09:57:45 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38447/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:57:46 INFO - PID 4201 | RSS: Main: 175759360 09:57:46 INFO - PID 4201 | 09:57:48 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38447/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:57:49 INFO - PID 4201 | RSS: Main: 175362048 09:57:49 INFO - PID 4201 | 09:57:51 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:57:52 INFO - PID 4201 | RSS: Main: 175992832 09:57:52 INFO - PID 4201 | 09:57:55 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:57:56 INFO - PID 4201 | RSS: Main: 175284224 09:57:56 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | Cycle 1(1): loaded http://localhost:38447/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38447/tests/kraken/driver.html?testName=ai-astar) 09:57:58 INFO - PID 4201 | RSS: Main: 177217536 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | __start_tp_report 09:57:58 INFO - PID 4201 | _x_x_mozilla_page_load 09:57:58 INFO - PID 4201 | _x_x_mozilla_page_load_details 09:57:58 INFO - PID 4201 | |i|pagename|runs| 09:57:58 INFO - PID 4201 | |0;ai-astar;100;108;102;110;109;111;112;100;111;111 09:57:58 INFO - PID 4201 | |1;audio-beat-detection;146;117;144;188;118;153;158;123;174;119 09:57:58 INFO - PID 4201 | |2;audio-dft;165;162;163;164;163;162;169;168;163;161 09:57:58 INFO - PID 4201 | |3;audio-fft;73;77;75;176;77;79;75;76;152;76 09:57:58 INFO - PID 4201 | |4;audio-oscillator;80;80;129;80;81;81;130;80;79;127 09:57:58 INFO - PID 4201 | |5;imaging-gaussian-blur;114;116;114;116;113;114;114;114;115;115 09:57:58 INFO - PID 4201 | |6;imaging-darkroom;151;151;151;151;150;151;150;153;151;151 09:57:58 INFO - PID 4201 | |7;imaging-desaturate;94;94;94;94;93;94;95;94;95;100 09:57:58 INFO - PID 4201 | |8;json-parse-financial;59;57;56;60;60;55;56;55;55;55 09:57:58 INFO - PID 4201 | |9;json-stringify-tinderbox;54;53;54;54;54;53;53;58;54;54 09:57:58 INFO - PID 4201 | |10;stanford-crypto-aes;80;77;79;78;79;78;77;77;77;85 09:57:58 INFO - PID 4201 | |11;stanford-crypto-ccm;131;132;126;129;130;137;132;148;132;185 09:57:58 INFO - PID 4201 | |12;stanford-crypto-pbkdf2;179;177;189;178;178;180;177;178;188;175 09:57:58 INFO - PID 4201 | |13;stanford-crypto-sha256-iterative;69;72;69;69;68;68;66;68;66;69 09:57:58 INFO - PID 4201 | __end_tp_report 09:57:58 INFO - PID 4201 | __start_cc_report 09:57:58 INFO - PID 4201 | _x_x_mozilla_cycle_collect,987 09:57:58 INFO - PID 4201 | __end_cc_report 09:57:58 INFO - PID 4201 | __startTimestamp1501001878471__endTimestamp 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | ------- Summary: start ------- 09:57:58 INFO - PID 4201 | Number of tests: 14 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#0] ai-astar Cycles:10 Average:107.40 Median:110.50 stddev:4.81 (4.4%) stddev-sans-first:4.29 09:57:58 INFO - PID 4201 | Values: 100.0 108.0 102.0 110.0 109.0 111.0 112.0 100.0 111.0 111.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#1] audio-beat-detection Cycles:10 Average:144.00 Median:149.50 stddev:24.92 (16.7%) stddev-sans-first:26.42 09:57:58 INFO - PID 4201 | Values: 146.0 117.0 144.0 188.0 118.0 153.0 158.0 123.0 174.0 119.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#2] audio-dft Cycles:10 Average:164.00 Median:163.50 stddev:2.62 (1.6%) stddev-sans-first:2.76 09:57:58 INFO - PID 4201 | Values: 165.0 162.0 163.0 164.0 163.0 162.0 169.0 168.0 163.0 161.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#3] audio-fft Cycles:10 Average:93.60 Median:76.00 stddev:37.57 (49.4%) stddev-sans-first:39.10 09:57:58 INFO - PID 4201 | Values: 73.0 77.0 75.0 176.0 77.0 79.0 75.0 76.0 152.0 76.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:80.00 stddev:23.46 (29.3%) stddev-sans-first:24.27 09:57:58 INFO - PID 4201 | Values: 80.0 80.0 129.0 80.0 81.0 81.0 130.0 80.0 79.0 127.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:114.50 stddev:0.97 (0.8%) stddev-sans-first:1.01 09:57:58 INFO - PID 4201 | Values: 114.0 116.0 114.0 116.0 113.0 114.0 114.0 114.0 115.0 115.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#6] imaging-darkroom Cycles:10 Average:151.00 Median:151.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 09:57:58 INFO - PID 4201 | Values: 151.0 151.0 151.0 151.0 150.0 151.0 150.0 153.0 151.0 151.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:1.95 (2.1%) stddev-sans-first:2.05 09:57:58 INFO - PID 4201 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 95.0 94.0 95.0 100.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#8] json-parse-financial Cycles:10 Average:56.80 Median:56.50 stddev:2.10 (3.7%) stddev-sans-first:2.07 09:57:58 INFO - PID 4201 | Values: 59.0 57.0 56.0 60.0 60.0 55.0 56.0 55.0 55.0 55.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#9] json-stringify-tinderbox Cycles:10 Average:54.10 Median:54.00 stddev:1.45 (2.7%) stddev-sans-first:1.54 09:57:58 INFO - PID 4201 | Values: 54.0 53.0 54.0 54.0 54.0 53.0 53.0 58.0 54.0 54.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:78.50 stddev:2.45 (3.1%) stddev-sans-first:2.55 09:57:58 INFO - PID 4201 | Values: 80.0 77.0 79.0 78.0 79.0 78.0 77.0 77.0 77.0 85.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:132.00 stddev:17.50 (13.3%) stddev-sans-first:18.36 09:57:58 INFO - PID 4201 | Values: 131.0 132.0 126.0 129.0 130.0 137.0 132.0 148.0 132.0 185.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.90 Median:178.50 stddev:4.72 (2.6%) stddev-sans-first:5.00 09:57:58 INFO - PID 4201 | Values: 179.0 177.0 189.0 178.0 178.0 180.0 177.0 178.0 188.0 175.0 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:1.71 (2.5%) stddev-sans-first:1.80 09:57:58 INFO - PID 4201 | Values: 69.0 72.0 69.0 69.0 68.0 68.0 66.0 68.0 66.0 69.0 09:57:58 INFO - PID 4201 | -------- Summary: end -------- 09:57:58 INFO - PID 4201 | 09:57:58 INFO - PID 4201 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:57:59 INFO - TEST-INFO | 4201: exit 0 09:57:59 INFO - TEST-OK | kraken | took 61550ms 09:57:59 INFO - SUITE-END | took 1173s 09:57:59 INFO - Completed test suite (00:19:33) 09:57:59 INFO - javascript benchmark 09:57:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8465.534011434404, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3112.59536260286, 3121.2040929562963, 3135.874565017399, 3143.5913331266934, 3186.2353035271526, 6043.274807023158, 6049.395060493954, 6218.875622487553, 6223.657698826562, 6226.813195604128, 5989.4010598940085, 6075.574709770307, 6100.450959413652, 6101.78643747469, 6167.352427995063, 5664.0, 5666.603337766358, 5726.770929162849, 5732.0828667413225, 5769.740361683735, 527.9100629334081, 5581.916271255935, 5646.915296270559, 5771.971140144294, 5788.681622510781, 5563.944360556383, 5606.719664016824, 5632.07070833312, 5693.943060569424, 5698.487136157745, 5626.156076588503, 5659.179418984266, 5707.800226992035, 5714.371419143891, 5751.798687045934, 5574.721263936827, 5622.690752008638, 5659.830205093854, 5669.518090962273, 5708.058170401879, 5997.070454079625, 6095.603785753912, 6132.938670613281, 6184.041473571604, 6195.473384762301, 6406.903896441557, 6520.0871877937125, 6532.085508028832, 6538.869222615521, 6684.766033188864, 6494.480441564663, 6582.0, 6675.732970681167, 6689.799306020827, 6707.966460167668, 6190.50475961922, 6191.195144631214, 6208.099825525273, 6274.435300822948, 6285.717142728588, 6848.075509806212, 6861.588304701734, 6921.688524016431, 6939.201991771011, 6948.131483564554, 6342.841428964312, 6401.711922963478, 6441.806745797634, 6511.479081673455, 6611.0, 6580.506462015368, 6606.570572912745, 6613.900791488131, 6664.333566643297, 6666.366695163952, 6538.934610653928, 6547.476201903836, 6650.567713098633, 6659.6670166491485, 6665.833354166185, 6337.556371053982, 6452.9677351612945, 6588.40704336612, 6684.064231007663, 6693.297203793629, 5210.322658054429, 5222.242774797709, 5223.791048358099, 5230.110881150214, 5246.580273578104, 4526.298423744325, 4534.727916325031, 4550.681452298308, 4560.429946256718, 4586.518415566317, 5546.639468434539, 5619.8595035124445, 5624.240727501735, 5624.521915637135, 5643.097104463266, 6510.0, 6521.902171467432, 6528.836779080561, 6531.575447595891, 6538.313477084933, 5607.0, 5622.212890195376, 5624.943750562524, 5639.689817060019, 5644.266245388156, 5302.602304827154, 5757.971210143923, 5853.414658534198, 5865.560082993792, 5875.383084776037, 6392.520560957947, 6400.839979000562, 6424.068510066014, 6479.060536222221, 6488.610683359014, 6457.321981191907, 6528.183977002875, 6585.275619681927, 6637.502187336066, 6673.9332606673315, 6362.936370636235, 6378.138951241523, 6403.775867844603, 6413.8717225656155, 6429.9678501607195, 6433.388828061326, 6466.191726034246, 6469.579477334147, 6477.255115661571, 6479.611223326616, 6562.8359291018105, 6594.439472644879, 6617.569857959217, 6618.933810661832, 6648.734050638114, 6435.967820160869, 6453.677316134081, 6474.741010359533, 6475.611463312216, 6545.5090868184125, 6267.122602835516, 6367.458766004941, 6432.774852880127, 6509.218893732783, 6518.0, 6316.273628532777, 6387.457066149429, 6396.616203027833, 6402.199725034371, 6409.0, 6472.0, 6488.0, 6538.378854009052, 6567.507436942344, 6571.34286571339, 6250.718717657625, 6263.404976527223, 6299.181106456132, 6358.268799088164, 6365.045243213555], "value": 5979.614736253315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.1991448380587, 829.7676650537851, 851.7019043334833, 890.3189060368815, 893.1024320557839, 40131.0, 40952.0, 41006.58993410065, 41017.97455063629, 41181.38227926576, 37030.8148459258, 37238.25523489529, 37350.0, 37369.81315093428, 37413.06467338318, 6712.832179195522, 6837.418819400357, 6861.519693621449, 6864.24493305736, 6876.0, 6806.3874251317375, 6870.484713646471, 6894.9655251723925, 6895.413889819371, 6910.723571057151, 6807.353301436356, 6877.931220687804, 6885.0, 6891.9310806892045, 6894.448444124458, 6813.761518346834, 6825.624590647512, 6830.6243156626615, 6835.282295358966, 6860.039594456755, 6213.813585592439, 6237.625742455467, 6262.467690246334, 6264.1543391642, 6281.057841323793, 33146.33707325864, 33470.665293346996, 33562.83218583916, 33659.495107573406, 33690.83154584236, 3379.932401351959, 3411.812350320731, 3414.078198886287, 3415.9487607685905, 3425.72594192464, 27690.307742306402, 27695.169144925683, 27721.306967325778, 27742.167734967985, 27767.444651107067, 4241.215375155606, 4248.98024474127, 4267.573242675739, 4326.372675961999, 4363.170997510463, 187789.0, 190184.0, 190829.0, 191724.0, 191820.0, 47064.5293547067, 48088.75955620199, 48093.75953120212, 48277.5172248273, 48278.517214828105, 49492.75253623709, 49538.75230623824, 49722.75138624284, 49827.0, 49853.25220121701, 16031.519054428385, 16074.75887861683, 16229.513114606581, 16232.188390580424, 16403.58991025234, 13669.0, 13723.725525489433, 13737.587872363845, 13740.244286564135, 13751.0, 12809.103362764703, 12987.675308117183, 13009.674758131123, 13053.216806991612, 13253.66865828362, 5551.72241387929, 5582.888342233133, 5683.744231509592, 5686.488216060575, 5691.573132015115, 952.4237836109054, 964.2575217082807, 975.5073687787622, 1008.5360734062267, 1009.0, 21649.242276520563, 21649.89175054115, 21938.451538711342, 22014.559708805733, 22205.0, 1079.109734469066, 1098.4288170151476, 1098.6374496416163, 1107.2249425402251, 1108.4734750993214, 11505.309681419141, 11584.189106762615, 11840.467178976967, 16107.194640268173, 16465.765067619977, 212681.0, 216005.0, 229610.0, 230672.0, 231742.0, 55086.0, 55372.446275537535, 55581.0, 55599.0, 55707.72146139244, 1440.071154105596, 1469.3975470057226, 1477.5593796048765, 1483.807105076333, 1485.5172069077378, 25883.741162588885, 26370.736292637597, 26432.867835660698, 26514.86742566275, 26580.0, 36011.27977440384, 36411.27177456489, 36432.81783591065, 36484.63515364812, 36493.27013459663, 190157.0, 191228.0, 192561.0, 193146.0, 194879.0, 32602.83698581492, 35625.64374356327, 36333.81833090818, 36385.63614363822, 36426.27147456983, 44567.77716111398, 46156.769216153705, 46281.305780412644, 46291.76854115708, 46323.768381157875, 32762.344753104328, 33263.83368083144, 33931.83034084814, 33997.66002339945, 34433.82783086068, 32846.029238245785, 33502.162445939044, 33798.32403351968, 33923.0, 34498.0, 36495.08762280964, 36857.44713829241, 36994.8150259247, 37024.0, 37495.25009499849, 31030.53454198234, 32633.0, 32660.673393265763, 33161.83419082889, 33354.0, 23967.28098157058, 24486.38784030342, 24492.632610511213, 24659.630105548073, 25002.0], "value": 16711.10138359072, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.3463851209619, 583.9562032847534, 590.3653572409657, 609.3388673289483, 614.2966303582398, 21618.675719864215, 22289.108435662554, 22425.0, 22511.77488225117, 22534.098636054558, 24691.259262222164, 25468.872655636744, 26663.600045999276, 26768.19695409136, 26780.866095669495, 6456.644884531361, 6560.147180866481, 6601.910684737013, 6606.14120164378, 6608.735650573972, 5856.414358564152, 6405.135306733578, 6468.191476065492, 6483.0, 6488.221413430395, 6401.071844582532, 6403.5197360197935, 6417.358264173568, 6425.261094974067, 6437.485001199892, 6392.232932048138, 6395.136656551353, 6423.421892029716, 6424.03639454081, 6428.260750013762, 5985.850353741192, 5987.1318658794235, 5990.520758339366, 6002.729877155539, 6005.729742161613, 28267.85866070677, 29155.70844291551, 29632.407351853057, 29699.554506682416, 30703.539446908315, 1897.6584214841323, 1958.7551556055494, 1963.3128405058258, 1964.4401345616432, 1966.1742068331191, 18621.0, 19354.419367419, 19373.612527749367, 19385.51536211592, 19404.708929366072, 2344.2029709898547, 2352.1179557665873, 2376.9524609507885, 2423.8060955123547, 2434.902603895842, 140731.0, 143008.0, 143179.0, 143588.0, 143970.0, 14986.925065374822, 15096.39614415411, 15199.31603077842, 15443.768343474856, 15659.843401565839, 21400.892995535232, 22558.77441225567, 22590.661140082913, 22967.655485167736, 24194.758052419602, 10688.412137332365, 10693.19801014927, 10706.1435085193, 10835.783284334428, 10920.344779313107, 9951.452670103068, 10050.69847904564, 10052.899471005343, 10112.443815590213, 10144.746381340377, 9150.908490915006, 9163.312751543528, 9186.63253469867, 9433.292503062297, 9499.145076943109, 2722.088100486359, 2757.4347258811895, 2822.223888430657, 2824.3927555575237, 2887.2926133097526, 935.6444551070549, 961.6105477281712, 969.7333233360882, 990.2127808392345, 1005.8483036922829, 19209.423717288224, 19471.707924381146, 19876.204951801767, 23447.41381465477, 25284.0, 1021.4637315409469, 1030.8714691161126, 1051.2090399987892, 1073.0932362153967, 1075.349413604758, 10696.304740191863, 11057.0, 11343.829842552368, 13015.934920325337, 13791.448342066205, 502349.0, 514249.0, 518803.0, 519452.0, 521440.0, 125813.0, 128108.0, 128667.35666321796, 129836.0, 132912.0, 1269.9873001270055, 1281.9487220511262, 1312.967175820612, 1322.7288405876773, 1327.024636891903, 20538.38384848457, 21665.025073872028, 22379.0, 22509.88745056264, 22775.31674049715, 45139.32291015703, 58508.70745646244, 58820.0, 60527.78944421175, 62367.81712640036, 78674.2132578667, 81409.59295203486, 81463.0, 82101.58949205215, 82401.58799205965, 19373.709394358815, 21777.455563611038, 21912.671309930683, 22090.77909220887, 22211.222607209318, 24397.780110994736, 24551.75448245566, 24621.38446538851, 24633.507329853663, 24636.753632463446, 18648.906755466134, 19161.0, 19272.518187044876, 19307.0, 19521.902390487958, 16384.590385240463, 17015.91492042532, 17243.3964811231, 17450.91274543619, 18000.279988800303, 19687.40937771869, 19744.30894918671, 19745.30891418851, 19835.206591736747, 19943.301984430476, 20389.0, 20783.896080519502, 21671.0, 21824.236151734614, 21834.781652183276, 17904.194311255764, 19338.516537086685, 19395.0, 19482.4155275342, 19667.70498442496], "value": 12615.650076829612, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1342.6978929740806, 1405.4799724102083, 1415.4126037694357, 1427.4504315838399, 1443.9711205775884, 13567.728645427085, 14168.291585420739, 14181.645458863533, 14193.929030354833, 14196.929015354921, 13691.589252322421, 14193.290335483236, 14716.705665886702, 14737.48418805342, 14804.6298842529, 2741.7121202273725, 2798.6921438641734, 2799.3981294021783, 2799.692033876272, 2801.229661842999, 2610.164747280871, 2652.5888487284406, 2719.578465337866, 2720.2111387697546, 2720.415110751188, 2717.605947137664, 2719.075514325129, 2746.8626568671584, 2748.367875388662, 2752.8898844046216, 2674.291312802099, 2674.772644325225, 2676.558367869299, 2684.2886635041727, 2696.164189101365, 2586.366340246652, 2595.2992691973063, 2623.7507436793476, 2625.3567875870344, 2626.500964816679, 13493.325333733274, 13799.793003104962, 13864.376103075387, 13881.167129972133, 13881.652958676013, 7399.741009064711, 7643.541387516767, 7645.273698998586, 7658.234176582353, 7684.654190561382, 4856.587190088847, 5059.747012649353, 5126.743662816844, 5138.383393992707, 5149.253358263031, 1756.5345183526376, 1829.8444632206279, 1866.934657287002, 1886.5377982394402, 1890.8014658460802, 23431.88284058586, 23459.76540234593, 23554.175603853953, 23725.288241352788, 23750.287491375286, 6483.222013358333, 6496.350364963466, 6505.934940650533, 6658.400743933069, 6703.497237707192, 15820.446284379992, 15861.841381586268, 15943.681126377407, 15943.681126377407, 15968.441104561285, 7395.519291246052, 7557.773266802004, 7559.092908850973, 7560.130584982798, 7565.130010048805, 6828.385445309847, 6852.862942741117, 6899.17209934801, 6969.163700355888, 7067.151941767021, 4599.655025873073, 4637.165310244189, 4971.403431588233, 5138.2806407103035, 5184.0, 1482.4885414531968, 1506.1941861104256, 1508.7058023685495, 1515.9242037898061, 1520.118331367804, 829.7884039569992, 837.9874301885354, 855.6449073634373, 871.4466313890725, 896.8699538567001, 8115.79710507242, 8168.2240187183315, 8299.336053115736, 8320.417570769989, 8344.207300306462, 952.9231967876256, 971.3783178765595, 999.9950050199503, 1003.448103543063, 1005.2209537608412, 4152.47315683581, 5068.41713202979, 5189.195674670432, 5208.062548741263, 5340.359156901197, 18394.26422943094, 18740.25038998452, 20214.595708085755, 20220.79779202189, 20227.089780959894, 18455.630887382176, 18797.248110075718, 18953.526161845788, 18981.62036759257, 19026.0, 9647.614095436244, 10225.948870255601, 10349.430781307101, 10386.688399348031, 10552.0, 7967.800804979922, 7971.521708697497, 8344.87482687748, 8371.41400101987, 8479.915200847912, 16665.666686666442, 17264.050477223875, 17328.22023008993, 17406.651866962336, 17580.296788128842, 16842.66314673675, 17230.13849307555, 17353.0, 17536.561585959942, 17748.467545973643, 11473.885261147281, 11765.176437649283, 11837.053035756946, 11837.704057398634, 11840.467178977138, 11439.199256051996, 11776.0, 12288.385580721108, 13163.078584498993, 13181.670458238237, 9751.902480975099, 9834.262430317898, 10460.0, 10560.947195263974, 10564.577416903545, 10260.230482713976, 10804.891951080388, 10842.24104312722, 10857.945710271399, 10962.506687198937, 9100.726978190665, 9360.625574976924, 9479.431234125974, 9529.285303602397, 9559.426434413957, 6190.380961903774, 6430.807075787734, 6524.380183882524, 6527.445167160845, 6694.397504224643, 6681.532292739462, 6709.496787741036, 7094.822629434099, 7179.497435179487, 7291.781246562611], "value": 6360.403221577009, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.04567230916626, 504.8889244366238, 525.7949399734104, 526.628726747643, 531.4924247343787, 5992.460678538931, 6255.499560035192, 6273.56085074045, 6280.770326534083, 6282.246130464345, 5999.940000599992, 6027.698615069251, 6030.065339872316, 6040.033594624861, 6044.153818465418, 4877.073356062355, 4897.338859254009, 4935.851924442263, 4956.479569645189, 4980.1782705853675, 4783.545563171493, 4922.55696987271, 4947.579455746266, 4950.975245123769, 4975.104481193383, 4820.614350851941, 4909.926351104718, 4915.926261106087, 4916.877078073059, 4936.654434189608, 4826.1795494765975, 4851.587615052725, 4870.732109733964, 4905.460399356068, 4943.802247910079, 4352.216601011816, 4365.2142614329405, 4449.443819522559, 4456.331550267453, 4494.8202071917085, 5942.138389933479, 6017.819465416044, 6035.124906888476, 6098.54260930432, 6101.450869421729, 5563.499285064323, 5604.1593760935775, 5630.690312032836, 5679.403662615448, 5748.310202775694, 6480.0, 6496.545241833073, 6496.9675151624415, 6518.348165183444, 6519.902201466982, 4790.856274311776, 4805.447373552051, 4851.805927762885, 4873.805047798084, 4897.363342765418, 6574.276829548746, 6660.4708798016845, 6761.256261811197, 6816.318368163194, 6927.445804335641, 4938.234573641092, 4940.728259945666, 4944.678595891256, 4961.801527938915, 4962.9751851241235, 5015.799368025238, 5033.597312215014, 5055.393352797614, 5055.873603159876, 5116.590672746172, 5528.502434780889, 5584.720763961785, 5635.436456354331, 5636.2954630671165, 5651.519620832275, 6187.535934804908, 6427.260865000491, 6496.967515162489, 6566.244881838554, 6580.276169621337, 1671.7743104680956, 1680.5378520906847, 1697.3634886917405, 1728.3691452619703, 1752.7458518514743, 1038.600138946496, 1193.6240084373394, 1240.3550153919912, 1242.944067516964, 1244.8070549064728, 5502.202180683778, 5593.0, 5624.268845050117, 5639.436056394327, 5668.149777533403, 6652.0, 6666.333366663295, 6668.766593169313, 6687.692746002718, 6709.463242940651, 5625.0, 5683.602147849693, 5701.00232459318, 5705.0, 5747.195392645033, 3493.615702272748, 3529.382358087376, 3532.6643968822923, 3536.734744894143, 3542.6811586957297, 6359.583358323807, 6451.387118223761, 6463.6768161591735, 6472.838179045561, 6488.351164883474, 6659.733610655617, 6747.5371489407, 6815.761448349284, 6848.794536163923, 6880.483963702743, 6409.0, 6409.551331406757, 6417.903731444126, 6418.26189988148, 6447.516436267299, 6542.378474044958, 6618.867622647616, 6622.768203112868, 6672.566283191577, 6708.26209116987, 6575.243846957661, 6589.769358072444, 6594.670266486561, 6634.535582509178, 6638.402543771083, 6364.745410183541, 6370.235571731422, 6410.358964103552, 6416.390442907917, 6545.21457425112, 5005.399352077774, 5031.371078615173, 5044.33536932804, 5045.2936588876855, 5053.69677819332, 6607.867842643216, 6620.602763834186, 6647.767328143492, 6676.732930682912, 6707.262201157966, 6255.186825712628, 6415.775447859302, 6457.3865482779065, 6474.539674923499, 6507.609543427409, 6342.23893132827, 6418.293987661447, 6444.2911279758255, 6484.0, 6556.409923106943, 6308.400701933309, 6374.681265936591, 6388.840278993062, 6418.454431373386, 6431.389018043278, 6525.813852359513, 6530.249021362414, 6564.737410503526, 6582.868342633216, 6629.574641452111, 6012.849678758066, 6240.095186198067, 6288.591241569284, 6363.331850155667, 6376.872462550816], "value": 4998.165830881017, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [895.0467751844286, 896.2919293757932, 909.858971859362, 924.0205382294768, 925.8148370325937, 17794.466166015023, 18957.715634265496, 19025.2389904404, 19094.33169839056, 19098.522536936565, 16570.83429165708, 16872.746908796376, 16935.49193524193, 19225.0, 21316.253931112413, 1053.8893416191286, 1071.132382769959, 1073.7745073534568, 1073.7798751256014, 1075.091547642245, 1055.5513906589706, 1059.729768908927, 1060.2313322840916, 1060.4697651174413, 1061.2306078093359, 1008.9115530851141, 1095.5782023920808, 1098.1434481104752, 1104.320842681753, 1118.429600903541, 1083.6965649618119, 1085.511519816082, 1086.4241951765498, 1087.1846115413439, 1087.3801932898252, 789.2856964447185, 793.611130546034, 793.714262865368, 798.0662624729081, 798.2735710503472, 18532.351367702202, 18668.906655466773, 18892.622147556973, 18901.527461813428, 20129.496762580908, 19853.205871765113, 20680.689789653166, 21023.0, 21259.787402125934, 21273.893630531747, 15266.923665381713, 15631.843681563267, 15726.921365393215, 15804.130772807492, 15829.841701582953, 3557.0395993081725, 3582.7492075554724, 3588.067102553329, 3591.2278860044953, 3600.945985810215, 116544.41727791392, 122328.0, 122381.0, 125981.0, 129393.0, 16878.987260764392, 17032.57418564521, 17159.57101072483, 17254.741178882075, 17325.480235592953, 29515.262118446783, 30599.541006884916, 30886.382272354876, 31063.689363106532, 31139.221519461742, 8438.156184381636, 8793.560321984003, 8811.69159079442, 8841.0, 8843.380963332633, 4100.0159961609, 4169.249535083653, 4171.290880550314, 4184.602462766033, 4202.516710578261, 5274.92087618686, 5290.1800220965815, 5395.433479484597, 5410.648307859977, 5413.864653383618, 1065.3767545985531, 1074.4735079810837, 1081.4214395298477, 1081.5133190064346, 1082.2910993299402, 639.7696829141505, 661.3683931845172, 679.1917618034523, 685.766839274649, 707.2785758526275, 11737.295762254293, 12349.135560510678, 13802.447902084004, 13876.0, 14145.080569762933, 591.9526437884958, 632.6646877155115, 645.2740666749906, 664.465105589995, 703.672792151652, 4979.875503112378, 5392.379876314274, 5865.090910908816, 5995.670238136944, 6182.1345011698395, 105497.0, 109793.0, 110729.89270107356, 111295.44352278186, 112225.0, 89329.0, 93455.0, 94359.0, 97294.0, 97876.0, 77145.61427192828, 80192.19807801965, 80208.0, 81498.59250703827, 82086.0, 12478.18891772032, 12974.481020759442, 13001.539353471753, 13189.472421103432, 13246.741559551827, 35504.64495355013, 37167.62832371642, 37781.622183777814, 37892.24215515729, 38048.80975595104, 64674.27675481302, 64952.00922381191, 67050.66474667596, 67188.99216511658, 69098.0, 18808.811911880704, 18997.90501047486, 19291.807081929, 19450.319238826574, 19501.0, 20475.8976205118, 20734.481637959172, 20998.58002839965, 21130.78869211288, 21278.574428511653, 18298.0, 18368.540786480444, 18528.629427411106, 18987.715184271972, 19328.0, 18845.340413086025, 19857.60284794267, 19979.20083196714, 20010.799892001476, 20147.496312592302, 17987.370442034466, 18442.446726598224, 18912.621747564695, 19107.426777196706, 19174.904125479283, 12805.679858003625, 12928.612141635766, 13559.118657287245, 13723.931380343434, 13732.45070197221, 13462.730745385234, 13827.79258311106, 14096.647583810487, 14706.485273015393, 14930.925345373204], "value": 9183.648282075503, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 108.0, 102.0, 110.0, 109.0, 111.0, 112.0, 100.0, 111.0, 111.0], "value": 107.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 144.0, 188.0, 118.0, 153.0, 158.0, 123.0, 174.0, 119.0], "value": 144.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 163.0, 164.0, 163.0, 162.0, 169.0, 168.0, 163.0, 161.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 75.0, 176.0, 77.0, 79.0, 75.0, 76.0, 152.0, 76.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 129.0, 80.0, 81.0, 81.0, 130.0, 80.0, 79.0, 127.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 114.0, 116.0, 113.0, 114.0, 114.0, 114.0, 115.0, 115.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 151.0, 151.0, 150.0, 151.0, 150.0, 153.0, 151.0, 151.0], "value": 151.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 94.0, 95.0, 100.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 57.0, 56.0, 60.0, 60.0, 55.0, 56.0, 55.0, 55.0, 55.0], "value": 56.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 54.0, 54.0, 53.0, 53.0, 58.0, 54.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 79.0, 78.0, 79.0, 78.0, 77.0, 77.0, 77.0, 85.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 132.0, 126.0, 129.0, 130.0, 137.0, 132.0, 148.0, 132.0, 185.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 189.0, 178.0, 178.0, 180.0, 177.0, 178.0, 188.0, 175.0], "value": 179.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 69.0, 69.0, 68.0, 68.0, 66.0, 68.0, 66.0, 69.0], "value": 68.4, "unit": "score"}]}]} 09:57:59 INFO - Return code: 0 09:57:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:57:59 INFO - # TBPL SUCCESS # 09:57:59 INFO - Running post-action listener: _package_coverage_data 09:57:59 INFO - Running post-action listener: _resource_record_post_action 09:57:59 INFO - [mozharness: 2017-07-25 16:57:59.268332Z] Finished run-tests step (success) 09:57:59 INFO - Running post-run listener: _resource_record_post_run 09:57:59 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 437776384; Read time: 16; Write time: 318688 09:57:59 INFO - TinderboxPrint: CPU usage
13.1% 09:57:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:57:59 INFO - TinderboxPrint: I/O write bytes / time
437,776,384 / 318,688 09:57:59 INFO - TinderboxPrint: CPU idle
8,253.4 (86.8%) 09:57:59 INFO - TinderboxPrint: CPU user
1,202.1 (12.6%) 09:57:59 INFO - TinderboxPrint: Swap in / out
0 / 0 09:57:59 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107618304; Read time: 0; Write time: 94004 09:57:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:57:59 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 330158080; Read time: 0; Write time: 224684 09:57:59 INFO - Running post-run listener: _upload_blobber_files 09:57:59 INFO - Blob upload gear active. 09:57:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:57:59 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 09:57:59 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'] 09:57:59 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 09:58:00 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:01 INFO - (blobuploader) - INFO - Done attempting. 09:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:01 INFO - (blobuploader) - INFO - Done attempting. 09:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:08 INFO - (blobuploader) - INFO - Done attempting. 09:58:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:58:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:58:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:09 INFO - (blobuploader) - INFO - Done attempting. 09:58:09 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:09 INFO - Return code: 0 09:58:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e82bfe093ff07353e1c46d51bda7ab44862ef6dd829fb9fe9f1784a971b4a9cdcc44979d3af709a34f23bb39de42a50d102736554815e1be6465c2c38a651c46", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3833b6ea0340e7bb20d2dd9e26fe79486ce6d7e9d0f63ae8db1c93b3481d0eb2134acd7ff4d68cf337a663717fbaff0d0affe3406e8253090e343a9413b7f2a7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7f2ee0df562fff3f5c1190a865be0a157cc655bc9d64afa3e1687c2063e6389fb8e555ef9ac797f550728c9f68e3930651d70793e3af295fdd8a665fb1e1b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b74e7f087eafd363d8a37ee3b076bcdb6728325488c74090346a3d18eb1bd225a9cf6e7e2e8c0c81f96a4982723160c7f56fe6f842f6a1e4add944918775c24d"} 09:58:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:09 INFO - Contents: 09:58:09 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e82bfe093ff07353e1c46d51bda7ab44862ef6dd829fb9fe9f1784a971b4a9cdcc44979d3af709a34f23bb39de42a50d102736554815e1be6465c2c38a651c46", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3833b6ea0340e7bb20d2dd9e26fe79486ce6d7e9d0f63ae8db1c93b3481d0eb2134acd7ff4d68cf337a663717fbaff0d0affe3406e8253090e343a9413b7f2a7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7f2ee0df562fff3f5c1190a865be0a157cc655bc9d64afa3e1687c2063e6389fb8e555ef9ac797f550728c9f68e3930651d70793e3af295fdd8a665fb1e1b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b74e7f087eafd363d8a37ee3b076bcdb6728325488c74090346a3d18eb1bd225a9cf6e7e2e8c0c81f96a4982723160c7f56fe6f842f6a1e4add944918775c24d"} 09:58:09 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:09 INFO - Copying logs to upload dir... 09:58:09 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.567479 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-07-25 09:58:08.996324) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-25 09:58:09.003323) ========= 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=826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e82bfe093ff07353e1c46d51bda7ab44862ef6dd829fb9fe9f1784a971b4a9cdcc44979d3af709a34f23bb39de42a50d102736554815e1be6465c2c38a651c46", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3833b6ea0340e7bb20d2dd9e26fe79486ce6d7e9d0f63ae8db1c93b3481d0eb2134acd7ff4d68cf337a663717fbaff0d0affe3406e8253090e343a9413b7f2a7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7f2ee0df562fff3f5c1190a865be0a157cc655bc9d64afa3e1687c2063e6389fb8e555ef9ac797f550728c9f68e3930651d70793e3af295fdd8a665fb1e1b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b74e7f087eafd363d8a37ee3b076bcdb6728325488c74090346a3d18eb1bd225a9cf6e7e2e8c0c81f96a4982723160c7f56fe6f842f6a1e4add944918775c24d"} build_url:https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011261 build_url: 'https://queue.taskcluster.net/v1/task/fWUCQvL-S1mphvFnR8y4xA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e82bfe093ff07353e1c46d51bda7ab44862ef6dd829fb9fe9f1784a971b4a9cdcc44979d3af709a34f23bb39de42a50d102736554815e1be6465c2c38a651c46", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3833b6ea0340e7bb20d2dd9e26fe79486ce6d7e9d0f63ae8db1c93b3481d0eb2134acd7ff4d68cf337a663717fbaff0d0affe3406e8253090e343a9413b7f2a7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ff7f2ee0df562fff3f5c1190a865be0a157cc655bc9d64afa3e1687c2063e6389fb8e555ef9ac797f550728c9f68e3930651d70793e3af295fdd8a665fb1e1b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b74e7f087eafd363d8a37ee3b076bcdb6728325488c74090346a3d18eb1bd225a9cf6e7e2e8c0c81f96a4982723160c7f56fe6f842f6a1e4add944918775c24d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-25 09:58:09.027398) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 09:58:09.027708) ========= 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=826874ccc97bd7bf541b4875000001d5-1500992254.937283-74076491 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-25 09:58:09.112868) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-25 09:58:09.116211) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-25 09:58:09.116503) ========= ========= Total master_lag: 0.23 =========