builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-089 starttime: 1502134997.83 results: success (0) revision: fde1450a4368d04e97174e2eb00fb48901179857 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:17.828134) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:17.828557) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:17.828831) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:17.862632) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:17.862888) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-07 12:43:17-- 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% 227M=0s 2017-08-07 12:43:18 (227 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.206909 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:18.110823) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:18.111148) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016536 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:18.163863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:18.164214) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fde1450a4368d04e97174e2eb00fb48901179857 --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 fde1450a4368d04e97174e2eb00fb48901179857 --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=8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-07 12:43:18,188 truncating revision to first 12 chars 2017-08-07 12:43:18,188 Setting DEBUG logging. 2017-08-07 12:43:18,188 attempt 1/10 2017-08-07 12:43:18,188 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fde1450a4368?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-07 12:43:18,504 unpacking tar archive at: mozilla-central-fde1450a4368/testing/mozharness/ program finished with exit code 0 elapsedTime=0.665570 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:18.842539) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:18.842841) ========= script_repo_revision: fde1450a4368d04e97174e2eb00fb48901179857 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:18.843231) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:18.843495) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-07 12:43:18.853866) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-07 12:43:18.854162) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:43:19 INFO - MultiFileLogger online at 20170807 12:43:19 in /builds/slave/test 12:43:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 12:43:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:43:19 INFO - {'append_to_log': False, 12:43:19 INFO - 'base_work_dir': '/builds/slave/test', 12:43:19 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 12:43:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:43:19 INFO - 'branch': 'Firefox-Non-PGO', 12:43:19 INFO - 'buildbot_json_path': 'buildprops.json', 12:43:19 INFO - 'code_coverage': False, 12:43:19 INFO - 'config_files': ('talos/linux_config.py',), 12:43:19 INFO - 'default_actions': ('clobber', 12:43:19 INFO - 'read-buildbot-config', 12:43:19 INFO - 'download-and-extract', 12:43:19 INFO - 'populate-webroot', 12:43:19 INFO - 'create-virtualenv', 12:43:19 INFO - 'install', 12:43:19 INFO - 'setup-mitmproxy', 12:43:19 INFO - 'run-tests'), 12:43:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:43:19 INFO - 'disable_ccov_upload': False, 12:43:19 INFO - 'download_minidump_stackwalk': True, 12:43:19 INFO - 'download_symbols': 'ondemand', 12:43:19 INFO - 'enable_stylo': False, 12:43:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:43:19 INFO - 'tooltool.py': '/tools/tooltool.py', 12:43:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:43:19 INFO - '/tools/misc-python/virtualenv.py')}, 12:43:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:43:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:43:19 INFO - 'gecko_profile': False, 12:43:19 INFO - 'gecko_profile_interval': 0, 12:43:19 INFO - 'installer_path': 'installer.exe', 12:43:19 INFO - 'log_level': 'info', 12:43:19 INFO - 'log_name': 'talos', 12:43:19 INFO - 'log_to_console': True, 12:43:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:43:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:43:19 INFO - 'opt_config_files': (), 12:43:19 INFO - 'pip_index': False, 12:43:19 INFO - 'suite': 'dromaeojs-e10s', 12:43:19 INFO - 'system_bits': '32', 12:43:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:43:19 INFO - 'title': 'talos-linux64-ix-089', 12:43:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:43:19 INFO - 'use_talos_json': True, 12:43:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:43:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:43:19 INFO - 'work_dir': 'build'} 12:43:19 INFO - [mozharness: 2017-08-07 19:43:19.070487Z] Running clobber step. 12:43:19 INFO - Running pre-action listener: _resource_record_pre_action 12:43:19 INFO - Running main action method: clobber 12:43:19 INFO - rmtree: /builds/slave/test/build 12:43:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:43:20 INFO - Running post-action listener: _resource_record_post_action 12:43:20 INFO - [mozharness: 2017-08-07 19:43:20.063672Z] Finished clobber step (success) 12:43:20 INFO - [mozharness: 2017-08-07 19:43:20.063775Z] Running read-buildbot-config step. 12:43:20 INFO - Running pre-action listener: _resource_record_pre_action 12:43:20 INFO - Running main action method: read_buildbot_config 12:43:20 INFO - Using buildbot properties: 12:43:20 INFO - { 12:43:20 INFO - "project": "", 12:43:20 INFO - "product": "firefox", 12:43:20 INFO - "who": "kwierso@gmail.com", 12:43:20 INFO - "installer_path": "public/build/target.tar.bz2", 12:43:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:43:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:43:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 12:43:20 INFO - "stage_platform": "linux64", 12:43:20 INFO - "basedir": "/builds/slave/test", 12:43:20 INFO - "buildnumber": 1064, 12:43:20 INFO - "platform": "ubuntu64_hw", 12:43:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:43:20 INFO - "slavebuilddir": "test", 12:43:20 INFO - "taskId": "Q-3bNsq-Ray21XJ6a-0baA", 12:43:20 INFO - "branch": "mozilla-central", 12:43:20 INFO - "script_repo_revision": "production", 12:43:20 INFO - "revision": "fde1450a4368d04e97174e2eb00fb48901179857", 12:43:20 INFO - "slavename": "talos-linux64-ix-089", 12:43:20 INFO - "repo_path": "mozilla-central" 12:43:20 INFO - } 12:43:20 INFO - Finding installer, test and symbols from parent task. 12:43:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q-3bNsq-Ray21XJ6a-0baA'}, attempt #1 12:43:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q-3bNsq-Ray21XJ6a-0baA'}, attempt #1 12:43:20 INFO - Task dependencies: eQ_3dHouQGmbtpzvbd4Udw 12:43:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw'}, attempt #1 12:43:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.tar.bz2 12:43:21 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.test_packages.json 12:43:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.crashreporter-symbols.zip 12:43:21 INFO - Running post-action listener: _resource_record_post_action 12:43:21 INFO - [mozharness: 2017-08-07 19:43:21.447820Z] Finished read-buildbot-config step (success) 12:43:21 INFO - [mozharness: 2017-08-07 19:43:21.448041Z] Running download-and-extract step. 12:43:21 INFO - Running pre-action listener: _resource_record_pre_action 12:43:21 INFO - Running main action method: download_and_extract 12:43:21 INFO - mkdir: /builds/slave/test/build/tests 12:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:21 INFO - trying https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.test_packages.json 12:43:21 INFO - Downloading https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:43:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:43:22 INFO - Downloaded 989 bytes. 12:43:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:43:22 INFO - Using the following test package requirements: 12:43:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:43:22 INFO - u'common': [u'target.common.tests.zip'], 12:43:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:43:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:43:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:43:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:43:22 INFO - u'mozbase': [u'target.common.tests.zip'], 12:43:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:43:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:43:22 INFO - u'web-platform': [u'target.common.tests.zip', 12:43:22 INFO - u'target.web-platform.tests.tar.gz'], 12:43:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:43:22 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:43:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.common.tests.zip 12:43:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:43:22 INFO - Fetch https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.common.tests.zip into memory 12:43:23 INFO - Content-Length response header: 54284421 12:43:23 INFO - Bytes received: 54284421 12:43:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:43:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.common.tests.zip 12:43:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:43:34 INFO - Fetch https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.common.tests.zip into memory 12:43:35 INFO - Content-Length response header: 54284421 12:43:35 INFO - Bytes received: 54284421 12:43:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.talos.tests.zip 12:43:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:43:48 INFO - Fetch https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.talos.tests.zip into memory 12:43:48 INFO - Content-Length response header: 14496008 12:43:48 INFO - Bytes received: 14496008 12:43: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')]} 12:43:49 INFO - trying https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.tar.bz2 12:43:49 INFO - Downloading https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:43:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:43:50 INFO - Downloaded 61763660 bytes. 12:43:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.tar.bz2 12:43:50 INFO - mkdir: /builds/slave/test/properties 12:43:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:43:50 INFO - Writing to file /builds/slave/test/properties/build_url 12:43:50 INFO - Contents: 12:43:50 INFO - build_url:https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.tar.bz2 12:43:50 INFO - Running post-action listener: _resource_record_post_action 12:43:50 INFO - Running post-action listener: set_extra_try_arguments 12:43:50 INFO - [mozharness: 2017-08-07 19:43:50.437537Z] Finished download-and-extract step (success) 12:43:50 INFO - [mozharness: 2017-08-07 19:43:50.437635Z] Running populate-webroot step. 12:43:50 INFO - Running pre-action listener: _resource_record_pre_action 12:43:50 INFO - Running main action method: populate_webroot 12:43:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:43:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:43:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:43:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:43:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:43:50 INFO - 'tests': ['damp', 'tps']}, 12:43:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:43:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:43:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:43:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:43:50 INFO - 'other-e10s': {'tests': ['a11yr', 12:43:50 INFO - 'ts_paint', 12:43:50 INFO - 'tpaint', 12:43:50 INFO - 'sessionrestore', 12:43:50 INFO - 'sessionrestore_many_windows', 12:43:50 INFO - 'sessionrestore_no_auto_restore', 12:43:50 INFO - 'tabpaint']}, 12:43:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:43:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 12:43:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:43:50 INFO - 'tsvgr_opacity', 12:43:50 INFO - 'tart', 12:43:50 INFO - 'tscrollx', 12:43:50 INFO - 'tsvg_static']}, 12:43:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:43:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:43:50 INFO - 'talos_options': ['--mitmproxy', 12:43:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:43:50 INFO - '--firstNonBlankPaint'], 12:43:50 INFO - 'tests': ['tp6_google', 12:43:50 INFO - 'tp6_youtube', 12:43:50 INFO - 'tp6_amazon', 12:43:50 INFO - 'tp6_facebook']}, 12:43:50 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:43:50 INFO - 'talos_options': ['--stylo', 12:43:50 INFO - '--mitmproxy', 12:43:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:43:50 INFO - '--firstNonBlankPaint'], 12:43:50 INFO - 'tests': ['tp6_google', 12:43:50 INFO - 'tp6_youtube', 12:43:50 INFO - 'tp6_amazon', 12:43:50 INFO - 'tp6_facebook']}, 12:43:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:43:50 INFO - 'talos_options': ['--stylo', 12:43:50 INFO - '--stylo-threads=1', 12:43:50 INFO - '--mitmproxy', 12:43:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:43:50 INFO - '--firstNonBlankPaint'], 12:43:50 INFO - 'tests': ['tp6_google', 12:43:50 INFO - 'tp6_youtube', 12:43:50 INFO - 'tp6_amazon', 12:43:50 INFO - 'tp6_facebook']}, 12:43:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:43:50 INFO - 'talos_options': ['--xperf_path', 12:43:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:43:50 INFO - 'tests': ['tp5n']}}} 12:43:50 INFO - Running post-action listener: _resource_record_post_action 12:43:50 INFO - [mozharness: 2017-08-07 19:43:50.445984Z] Finished populate-webroot step (success) 12:43:50 INFO - [mozharness: 2017-08-07 19:43:50.446074Z] Running create-virtualenv step. 12:43:50 INFO - Running pre-action listener: _resource_record_pre_action 12:43:50 INFO - Running main action method: create_virtualenv 12:43:50 INFO - Creating virtualenv /builds/slave/test/build/venv 12:43:50 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 12:43:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:43:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:43:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:43:50 INFO - Using real prefix '/usr' 12:43:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:43:51 INFO - Installing distribute.............................................................................................................................................................................................done. 12:43:53 INFO - Installing pip.................done. 12:43:53 INFO - Return code: 0 12:43:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:43:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:43:53 INFO - Reading from file tmpfile_stdout 12:43:53 INFO - Output received: 12:43:53 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) 12:43:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:43:53 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')]} 12:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:53 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')]} 12:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:53 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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:43:53 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 12:43:53 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 12:43:53 INFO - Using env: {'DISPLAY': ':0', 12:43:53 INFO - 'HOME': '/home/cltbld', 12:43:53 INFO - 'LANG': 'en_US.UTF-8', 12:43:53 INFO - 'LANGUAGE': 'en_US:en', 12:43:53 INFO - 'LOGNAME': 'cltbld', 12:43:53 INFO - 'MAIL': '/var/mail/cltbld', 12:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:53 INFO - 'MOZ_NO_REMOTE': '1', 12:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:53 INFO - 'NO_EM_RESTART': '1', 12:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:53 INFO - 'PWD': '/builds/slave/test', 12:43:53 INFO - 'SHELL': '/bin/bash', 12:43:53 INFO - 'SHLVL': '1', 12:43:53 INFO - 'TERM': 'linux', 12:43:53 INFO - 'TMOUT': '86400', 12:43:53 INFO - 'USER': 'cltbld', 12:43:53 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:53 INFO - '_': '/tools/buildbot/bin/python'} 12:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:53 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 12:43:53 INFO - Cleaning up... 12:43:53 INFO - Return code: 0 12:43:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:43:53 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')]} 12:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:53 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')]} 12:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:53 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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:43:53 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 12:43:53 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 12:43:53 INFO - Using env: {'DISPLAY': ':0', 12:43:53 INFO - 'HOME': '/home/cltbld', 12:43:53 INFO - 'LANG': 'en_US.UTF-8', 12:43:53 INFO - 'LANGUAGE': 'en_US:en', 12:43:53 INFO - 'LOGNAME': 'cltbld', 12:43:53 INFO - 'MAIL': '/var/mail/cltbld', 12:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:53 INFO - 'MOZ_NO_REMOTE': '1', 12:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:53 INFO - 'NO_EM_RESTART': '1', 12:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:53 INFO - 'PWD': '/builds/slave/test', 12:43:53 INFO - 'SHELL': '/bin/bash', 12:43:53 INFO - 'SHLVL': '1', 12:43:53 INFO - 'TERM': 'linux', 12:43:53 INFO - 'TMOUT': '86400', 12:43:53 INFO - 'USER': 'cltbld', 12:43:53 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:53 INFO - '_': '/tools/buildbot/bin/python'} 12:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:53 INFO - Downloading/unpacking psutil>=3.1.1 12:43: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 12:43: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 12:43: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 12:43: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 12:43:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:43:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:43:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:43:55 INFO - Installing collected packages: psutil 12:43:55 INFO - Running setup.py install for psutil 12:43:55 INFO - building 'psutil._psutil_linux' extension 12:43:55 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 12:43:55 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 12:43:55 INFO - building 'psutil._psutil_posix' extension 12:43:55 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 12:43:56 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 12:43:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:43:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:43:56 INFO - Successfully installed psutil 12:43:56 INFO - Cleaning up... 12:43:56 INFO - Return code: 0 12:43:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:56 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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:43:56 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 12:43:56 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 12:43:56 INFO - Using env: {'DISPLAY': ':0', 12:43:56 INFO - 'HOME': '/home/cltbld', 12:43:56 INFO - 'LANG': 'en_US.UTF-8', 12:43:56 INFO - 'LANGUAGE': 'en_US:en', 12:43:56 INFO - 'LOGNAME': 'cltbld', 12:43:56 INFO - 'MAIL': '/var/mail/cltbld', 12:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:56 INFO - 'MOZ_NO_REMOTE': '1', 12:43:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:56 INFO - 'NO_EM_RESTART': '1', 12:43:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:56 INFO - 'PWD': '/builds/slave/test', 12:43:56 INFO - 'SHELL': '/bin/bash', 12:43:56 INFO - 'SHLVL': '1', 12:43:56 INFO - 'TERM': 'linux', 12:43:56 INFO - 'TMOUT': '86400', 12:43:56 INFO - 'USER': 'cltbld', 12:43:56 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:56 INFO - '_': '/tools/buildbot/bin/python'} 12:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:43: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 12:43: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 12:43: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 12:43: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 12:43:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:43:57 INFO - Installing collected packages: mozsystemmonitor 12:43:57 INFO - Running setup.py install for mozsystemmonitor 12:43:57 INFO - Successfully installed mozsystemmonitor 12:43:57 INFO - Cleaning up... 12:43:57 INFO - Return code: 0 12:43:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:43: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')]} 12:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43: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')]} 12:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:43:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:43:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:43:57 INFO - Using env: {'DISPLAY': ':0', 12:43:57 INFO - 'HOME': '/home/cltbld', 12:43:57 INFO - 'LANG': 'en_US.UTF-8', 12:43:57 INFO - 'LANGUAGE': 'en_US:en', 12:43:57 INFO - 'LOGNAME': 'cltbld', 12:43:57 INFO - 'MAIL': '/var/mail/cltbld', 12:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:57 INFO - 'MOZ_NO_REMOTE': '1', 12:43:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:57 INFO - 'NO_EM_RESTART': '1', 12:43:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:57 INFO - 'PWD': '/builds/slave/test', 12:43:57 INFO - 'SHELL': '/bin/bash', 12:43:57 INFO - 'SHLVL': '1', 12:43:57 INFO - 'TERM': 'linux', 12:43:57 INFO - 'TMOUT': '86400', 12:43:57 INFO - 'USER': 'cltbld', 12:43:57 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:57 INFO - '_': '/tools/buildbot/bin/python'} 12:43:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:57 INFO - Downloading/unpacking jsonschema==2.5.1 12:43:57 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 12:43:57 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 12:43:57 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 12:43:57 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 12:43:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:43:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:59 INFO - Downloading functools32-3.2.3-2.tar.gz 12:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:43:59 INFO - warning: no files found matching '*.txt' 12:43:59 INFO - no previously-included directories found matching 'build' 12:43:59 INFO - no previously-included directories found matching 'dist' 12:43:59 INFO - no previously-included directories found matching '.git*' 12:43:59 INFO - Installing collected packages: jsonschema, functools32 12:43:59 INFO - Running setup.py install for functools32 12:43:59 INFO - warning: no files found matching '*.txt' 12:43:59 INFO - no previously-included directories found matching 'build' 12:43:59 INFO - no previously-included directories found matching 'dist' 12:43:59 INFO - no previously-included directories found matching '.git*' 12:43:59 INFO - Successfully installed jsonschema functools32 12:43:59 INFO - Cleaning up... 12:43:59 INFO - Return code: 0 12:43:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:43:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:43:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:43:59 INFO - Using env: {'DISPLAY': ':0', 12:43:59 INFO - 'HOME': '/home/cltbld', 12:43:59 INFO - 'LANG': 'en_US.UTF-8', 12:43:59 INFO - 'LANGUAGE': 'en_US:en', 12:43:59 INFO - 'LOGNAME': 'cltbld', 12:43:59 INFO - 'MAIL': '/var/mail/cltbld', 12:43:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:59 INFO - 'MOZ_NO_REMOTE': '1', 12:43:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:59 INFO - 'NO_EM_RESTART': '1', 12:43:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:59 INFO - 'PWD': '/builds/slave/test', 12:43:59 INFO - 'SHELL': '/bin/bash', 12:43:59 INFO - 'SHLVL': '1', 12:43:59 INFO - 'TERM': 'linux', 12:43:59 INFO - 'TMOUT': '86400', 12:43:59 INFO - 'USER': 'cltbld', 12:43:59 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:43:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:59 INFO - '_': '/tools/buildbot/bin/python'} 12:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:43:59 INFO - Cleaning up... 12:43:59 INFO - Return code: 0 12:43:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:43:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:43:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:43:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:43:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:43:59 INFO - Using env: {'DISPLAY': ':0', 12:43:59 INFO - 'HOME': '/home/cltbld', 12:43:59 INFO - 'LANG': 'en_US.UTF-8', 12:43:59 INFO - 'LANGUAGE': 'en_US:en', 12:43:59 INFO - 'LOGNAME': 'cltbld', 12:43:59 INFO - 'MAIL': '/var/mail/cltbld', 12:43:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:59 INFO - 'MOZ_NO_REMOTE': '1', 12:43:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:59 INFO - 'NO_EM_RESTART': '1', 12:43:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:59 INFO - 'PWD': '/builds/slave/test', 12:43:59 INFO - 'SHELL': '/bin/bash', 12:43:59 INFO - 'SHLVL': '1', 12:43:59 INFO - 'TERM': 'linux', 12:43:59 INFO - 'TMOUT': '86400', 12:43:59 INFO - 'USER': 'cltbld', 12:43:59 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:43:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:59 INFO - '_': '/tools/buildbot/bin/python'} 12:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:43:59 INFO - Downloading/unpacking blobuploader==1.2.4 12:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:43:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:43:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:44:00 INFO - Downloading blobuploader-1.2.4.tar.gz 12:44:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:44:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:44: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 12:44: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 12:44: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 12:44: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 12:44:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:44:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:44: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 12:44: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 12:44: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 12:44: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 12:44:01 INFO - Downloading docopt-0.6.1.tar.gz 12:44:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:44:01 INFO - Installing collected packages: blobuploader, requests, docopt 12:44:01 INFO - Running setup.py install for blobuploader 12:44:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:44:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:44:01 INFO - Running setup.py install for requests 12:44:01 INFO - Running setup.py install for docopt 12:44:02 INFO - Successfully installed blobuploader requests docopt 12:44:02 INFO - Cleaning up... 12:44:02 INFO - Return code: 0 12:44:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:02 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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:44:02 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 12:44:02 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 12:44:02 INFO - Using env: {'DISPLAY': ':0', 12:44:02 INFO - 'HOME': '/home/cltbld', 12:44:02 INFO - 'LANG': 'en_US.UTF-8', 12:44:02 INFO - 'LANGUAGE': 'en_US:en', 12:44:02 INFO - 'LOGNAME': 'cltbld', 12:44:02 INFO - 'MAIL': '/var/mail/cltbld', 12:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:02 INFO - 'MOZ_NO_REMOTE': '1', 12:44:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:02 INFO - 'NO_EM_RESTART': '1', 12:44:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:02 INFO - 'PWD': '/builds/slave/test', 12:44:02 INFO - 'SHELL': '/bin/bash', 12:44:02 INFO - 'SHLVL': '1', 12:44:02 INFO - 'TERM': 'linux', 12:44:02 INFO - 'TMOUT': '86400', 12:44:02 INFO - 'USER': 'cltbld', 12:44:02 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:02 INFO - '_': '/tools/buildbot/bin/python'} 12:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:44:02 INFO - Running setup.py (path:/tmp/pip-QgFhp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:44:02 INFO - Running setup.py (path:/tmp/pip-cwHksh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:44:02 INFO - Running setup.py (path:/tmp/pip-7PrtCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:44:02 INFO - Running setup.py (path:/tmp/pip-60IDDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:44:02 INFO - Running setup.py (path:/tmp/pip-PxCuRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:44:02 INFO - Running setup.py (path:/tmp/pip-h8epHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:44:02 INFO - Running setup.py (path:/tmp/pip-LW4pDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:44:02 INFO - Running setup.py (path:/tmp/pip-nYQc15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:44:02 INFO - Running setup.py (path:/tmp/pip-gxwt7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:44:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:44:02 INFO - Running setup.py (path:/tmp/pip-IPV1yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:44:03 INFO - Running setup.py (path:/tmp/pip-CwHYh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:44:03 INFO - Running setup.py (path:/tmp/pip-IwxOVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:44:03 INFO - Running setup.py (path:/tmp/pip-abagvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:44:03 INFO - Running setup.py (path:/tmp/pip-pWtgWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:03 INFO - Running setup.py (path:/tmp/pip-wNRdG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:44:03 INFO - Running setup.py (path:/tmp/pip-ochZzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:44:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:44:03 INFO - Running setup.py (path:/tmp/pip-oZqvpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:44:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:44:03 INFO - Running setup.py install for manifestparser 12:44:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Running setup.py install for mozcrash 12:44:03 INFO - Running setup.py install for mozdebug 12:44:03 INFO - Running setup.py install for mozdevice 12:44:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:44:03 INFO - Running setup.py install for mozfile 12:44:04 INFO - Running setup.py install for mozhttpd 12:44:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Running setup.py install for mozinfo 12:44:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Running setup.py install for mozInstall 12:44:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Running setup.py install for mozleak 12:44:04 INFO - Running setup.py install for mozlog 12:44:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Running setup.py install for moznetwork 12:44:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Running setup.py install for mozprocess 12:44:04 INFO - Running setup.py install for mozprofile 12:44:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:44:04 INFO - Running setup.py install for mozrunner 12:44:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:44:05 INFO - Running setup.py install for mozscreenshot 12:44:05 INFO - Running setup.py install for moztest 12:44:05 INFO - Running setup.py install for mozversion 12:44:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:44:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:44:05 INFO - Cleaning up... 12:44:05 INFO - Return code: 0 12:44:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44:05 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')]} 12:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:05 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')]} 12:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:05 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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:44:05 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 12:44:05 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 12:44:05 INFO - Using env: {'DISPLAY': ':0', 12:44:05 INFO - 'HOME': '/home/cltbld', 12:44:05 INFO - 'LANG': 'en_US.UTF-8', 12:44:05 INFO - 'LANGUAGE': 'en_US:en', 12:44:05 INFO - 'LOGNAME': 'cltbld', 12:44:05 INFO - 'MAIL': '/var/mail/cltbld', 12:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:05 INFO - 'MOZ_NO_REMOTE': '1', 12:44:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:05 INFO - 'NO_EM_RESTART': '1', 12:44:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:05 INFO - 'PWD': '/builds/slave/test', 12:44:05 INFO - 'SHELL': '/bin/bash', 12:44:05 INFO - 'SHLVL': '1', 12:44:05 INFO - 'TERM': 'linux', 12:44:05 INFO - 'TMOUT': '86400', 12:44:05 INFO - 'USER': 'cltbld', 12:44:05 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:05 INFO - '_': '/tools/buildbot/bin/python'} 12:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:44:05 INFO - Running setup.py (path:/tmp/pip-d9oVdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:44:05 INFO - Running setup.py (path:/tmp/pip-lHMbSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:44:05 INFO - Running setup.py (path:/tmp/pip-s_dv46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:44:05 INFO - Running setup.py (path:/tmp/pip-slsY3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:44:05 INFO - Running setup.py (path:/tmp/pip-Fbnrbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:44:05 INFO - Running setup.py (path:/tmp/pip-iCZIBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:44:05 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)) 12:44:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:44:05 INFO - Running setup.py (path:/tmp/pip-kCl3iD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:44:06 INFO - Running setup.py (path:/tmp/pip-6k0Gb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:44:06 INFO - Running setup.py (path:/tmp/pip-2dex52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:44:06 INFO - Running setup.py (path:/tmp/pip-peS8D5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:44:06 INFO - Running setup.py (path:/tmp/pip-42p3pM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:44:06 INFO - Running setup.py (path:/tmp/pip-GN5zb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:44:06 INFO - Running setup.py (path:/tmp/pip-PWjnVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:44:06 INFO - Running setup.py (path:/tmp/pip-opLQ6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:06 INFO - Running setup.py (path:/tmp/pip-7UjoNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:44:06 INFO - Running setup.py (path:/tmp/pip-axJHQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:44:06 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)) 12:44:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:44:06 INFO - Running setup.py (path:/tmp/pip-D5fZEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:44:06 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)) 12:44:06 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)) 12:44:06 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)) 12:44:06 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)) 12:44:06 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)) 12:44:06 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)) 12:44:06 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)) 12:44:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:44:07 INFO - Downloading blessings-1.6.tar.gz 12:44:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:44:07 INFO - Installing collected packages: blessings 12:44:07 INFO - Running setup.py install for blessings 12:44:08 INFO - Successfully installed blessings 12:44:08 INFO - Cleaning up... 12:44:08 INFO - Return code: 0 12:44:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:44:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:44:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:44:08 INFO - Reading from file tmpfile_stdout 12:44:08 INFO - Current package versions: 12:44:08 INFO - argparse == 1.2.1 12:44:08 INFO - blessings == 1.6 12:44:08 INFO - blobuploader == 1.2.4 12:44:08 INFO - docopt == 0.6.1 12:44:08 INFO - functools32 == 3.2.3-2 12:44:08 INFO - jsonschema == 2.5.1 12:44:08 INFO - manifestparser == 1.1 12:44:08 INFO - mozInstall == 1.13 12:44:08 INFO - mozcrash == 1.0 12:44:08 INFO - mozdebug == 0.1 12:44:08 INFO - mozdevice == 0.51 12:44:08 INFO - mozfile == 1.2 12:44:08 INFO - mozhttpd == 0.7 12:44:08 INFO - mozinfo == 0.10 12:44:08 INFO - mozleak == 0.1 12:44:08 INFO - mozlog == 3.5 12:44:08 INFO - moznetwork == 0.27 12:44:08 INFO - mozprocess == 0.25 12:44:08 INFO - mozprofile == 0.28 12:44:08 INFO - mozrunner == 6.13 12:44:08 INFO - mozscreenshot == 0.1 12:44:08 INFO - mozsystemmonitor == 0.3 12:44:08 INFO - moztest == 0.8 12:44:08 INFO - mozversion == 1.4 12:44:08 INFO - psutil == 3.1.1 12:44:08 INFO - requests == 1.2.3 12:44:08 INFO - wsgiref == 0.1.2 12:44:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:44:08 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')]} 12:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:08 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')]} 12:44:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:08 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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:44:08 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 12:44:08 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 12:44:08 INFO - Using env: {'DISPLAY': ':0', 12:44:08 INFO - 'HOME': '/home/cltbld', 12:44:08 INFO - 'LANG': 'en_US.UTF-8', 12:44:08 INFO - 'LANGUAGE': 'en_US:en', 12:44:08 INFO - 'LOGNAME': 'cltbld', 12:44:08 INFO - 'MAIL': '/var/mail/cltbld', 12:44:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:08 INFO - 'MOZ_NO_REMOTE': '1', 12:44:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:08 INFO - 'NO_EM_RESTART': '1', 12:44:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:08 INFO - 'PWD': '/builds/slave/test', 12:44:08 INFO - 'SHELL': '/bin/bash', 12:44:08 INFO - 'SHLVL': '1', 12:44:08 INFO - 'TERM': 'linux', 12:44:08 INFO - 'TMOUT': '86400', 12:44:08 INFO - 'USER': 'cltbld', 12:44:08 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:44:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:08 INFO - '_': '/tools/buildbot/bin/python'} 12:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:08 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)) 12:44:08 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)) 12:44:08 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)) 12:44:08 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)) 12:44:08 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)) 12:44:08 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)) 12:44:08 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)) 12:44:08 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)) 12:44:08 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)) 12:44:08 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:44:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:44:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:44:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:44:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:44:09 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)) 12:44:09 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)) 12:44:09 INFO - Installing collected packages: simplejson 12:44:09 INFO - Running setup.py install for simplejson 12:44:09 INFO - building 'simplejson._speedups' extension 12:44:09 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 12:44:10 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 12:44:10 INFO - Successfully installed simplejson 12:44:10 INFO - Cleaning up... 12:44:10 INFO - Return code: 0 12:44:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:44:10 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')]} 12:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:44:10 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')]} 12:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:44:10 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 0x22211f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23eea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2591d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, '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': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', '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 12:44:10 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 12:44:10 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 12:44:10 INFO - Using env: {'DISPLAY': ':0', 12:44:10 INFO - 'HOME': '/home/cltbld', 12:44:10 INFO - 'LANG': 'en_US.UTF-8', 12:44:10 INFO - 'LANGUAGE': 'en_US:en', 12:44:10 INFO - 'LOGNAME': 'cltbld', 12:44:10 INFO - 'MAIL': '/var/mail/cltbld', 12:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:10 INFO - 'MOZ_NO_REMOTE': '1', 12:44:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:10 INFO - 'NO_EM_RESTART': '1', 12:44:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:10 INFO - 'PWD': '/builds/slave/test', 12:44:10 INFO - 'SHELL': '/bin/bash', 12:44:10 INFO - 'SHLVL': '1', 12:44:10 INFO - 'TERM': 'linux', 12:44:10 INFO - 'TMOUT': '86400', 12:44:10 INFO - 'USER': 'cltbld', 12:44:10 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:10 INFO - '_': '/tools/buildbot/bin/python'} 12:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:44:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:44:11 INFO - Cleaning up... 12:44:11 INFO - Return code: 0 12:44:11 INFO - Running post-action listener: _resource_record_post_action 12:44:11 INFO - Running post-action listener: _start_resource_monitoring 12:44:11 INFO - Starting resource monitoring. 12:44:11 INFO - [mozharness: 2017-08-07 19:44:11.070076Z] Finished create-virtualenv step (success) 12:44:11 INFO - [mozharness: 2017-08-07 19:44:11.071079Z] Running install step. 12:44:11 INFO - Running pre-action listener: _resource_record_pre_action 12:44:11 INFO - Running main action method: install 12:44:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:44:11 INFO - Reading from file tmpfile_stdout 12:44:11 INFO - Detecting whether we're running mozinstall >=1.0... 12:44:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:44:11 INFO - Reading from file tmpfile_stdout 12:44:11 INFO - Output received: 12:44:11 INFO - Usage: mozinstall [options] installer 12:44:11 INFO - Options: 12:44:11 INFO - -h, --help show this help message and exit 12:44:11 INFO - -d DEST, --destination=DEST 12:44:11 INFO - Directory to install application into. [default: 12:44:11 INFO - "/builds/slave/test"] 12:44:11 INFO - --app=APP Application being installed. [default: firefox] 12:44:11 INFO - mkdir: /builds/slave/test/build/application 12:44:11 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'] 12:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:44:29 INFO - Reading from file tmpfile_stdout 12:44:29 INFO - Output received: 12:44:29 INFO - /builds/slave/test/build/application/firefox/firefox 12:44:29 INFO - Running post-action listener: _resource_record_post_action 12:44:29 INFO - [mozharness: 2017-08-07 19:44:29.363122Z] Finished install step (success) 12:44:29 INFO - [mozharness: 2017-08-07 19:44:29.363250Z] Running setup-mitmproxy step. 12:44:29 INFO - Running pre-action listener: _resource_record_pre_action 12:44:29 INFO - Running main action method: setup_mitmproxy 12:44:29 INFO - Skipping: mitmproxy is not required 12:44:29 INFO - Running post-action listener: _resource_record_post_action 12:44:29 INFO - [mozharness: 2017-08-07 19:44:29.363885Z] Finished setup-mitmproxy step (success) 12:44:29 INFO - [mozharness: 2017-08-07 19:44:29.364076Z] Running run-tests step. 12:44:29 INFO - Running pre-action listener: _resource_record_pre_action 12:44:29 INFO - Running pre-action listener: _set_gcov_prefix 12:44:29 INFO - Running main action method: run_tests 12:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:44:29 INFO - Python 2.7.3 12:44:29 INFO - Return code: 0 12:44:29 INFO - grabbing minidump binary from tooltool 12:44:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:44:29 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 0x258eeb0>, '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 0x258fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2590240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:44:29 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 12:44:29 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 12:44:29 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 12:44:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:44:29 INFO - Return code: 0 12:44:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:44:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:44:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:44:29 INFO - ENV: RUST_BACKTRACE is now 1 12:44:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:44:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:44:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 12:44:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --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 12:44:29 INFO - Using env: {'DISPLAY': ':0', 12:44:29 INFO - 'HOME': '/home/cltbld', 12:44:29 INFO - 'LANG': 'en_US.UTF-8', 12:44:29 INFO - 'LANGUAGE': 'en_US:en', 12:44:29 INFO - 'LOGNAME': 'cltbld', 12:44:29 INFO - 'MAIL': '/var/mail/cltbld', 12:44:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:44:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:44:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:44:29 INFO - 'MOZ_NO_REMOTE': '1', 12:44:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:44:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:44:29 INFO - 'NO_EM_RESTART': '1', 12:44:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:44:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:44:29 INFO - 'PWD': '/builds/slave/test', 12:44:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:44:29 INFO - 'RUST_BACKTRACE': '1', 12:44:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:44:29 INFO - 'SHELL': '/bin/bash', 12:44:29 INFO - 'SHLVL': '1', 12:44:29 INFO - 'TERM': 'linux', 12:44:29 INFO - 'TMOUT': '86400', 12:44:29 INFO - 'USER': 'cltbld', 12:44:29 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505', 12:44:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:44:29 INFO - '_': '/tools/buildbot/bin/python'} 12:44:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 12:44:29 INFO - mozversion application_buildid: 20170807190002 12:44:29 INFO - mozversion application_changeset: fde1450a4368d04e97174e2eb00fb48901179857 12:44:29 INFO - mozversion application_display_name: Nightly 12:44:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:44:29 INFO - mozversion application_name: Firefox 12:44:29 INFO - mozversion application_remotingname: firefox 12:44:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:44:29 INFO - mozversion application_vendor: Mozilla 12:44:29 INFO - mozversion application_version: 57.0a1 12:44:29 INFO - mozversion platform_buildid: 20170807190002 12:44:29 INFO - mozversion platform_changeset: fde1450a4368d04e97174e2eb00fb48901179857 12:44:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:44:29 INFO - mozversion platform_version: 57.0a1 12:44:29 INFO - using testdate: 1502135069 12:44:29 INFO - actual date: 1502135069 12:44:29 INFO - starting webserver on 'localhost:59168' 12:44:29 INFO - SUITE-START | Running 2 tests 12:44:29 INFO - TEST-START | dromaeo_css 12:44:29 INFO - Initialising browser for dromaeo_css test... 12:44:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59168/getInfo.html -profile /tmp/tmpMzGMvB/profile 12:44:29 INFO - TEST-INFO | started process 3318 (/builds/slave/test/build/application/firefox/firefox http://localhost:59168/getInfo.html) 12:44:37 INFO - TEST-INFO | 3318: exit 0 12:44:37 INFO - Browser initialized. 12:44:37 INFO - Running cycle 1/1 for dromaeo_css test... 12:44:37 INFO - TEST-INFO | started process 3526 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMzGMvB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:44:38 INFO - PID 3526 | 12:44:38 INFO - PID 3526 | (/builds/slave/test/build/application/firefox/firefox:3574): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:44:38 INFO - PID 3526 | 12:44:48 INFO - PID 3526 | RSS: Main: 167964672 12:44:48 INFO - PID 3526 | 12:47:48 INFO - PID 3526 | Cycle 1(1): loaded http://localhost:59168/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59168/tests/dromaeo/cssquery-ext.html) 12:47:49 INFO - PID 3526 | RSS: Main: 172228608 12:47:49 INFO - PID 3526 | 12:50:51 INFO - PID 3526 | Cycle 1(1): loaded http://localhost:59168/tests/dromaeo/cssquery-ext.html (next: http://localhost:59168/tests/dromaeo/cssquery-jquery.html) 12:50:52 INFO - PID 3526 | RSS: Main: 169971712 12:50:52 INFO - PID 3526 | 12:53:53 INFO - PID 3526 | Cycle 1(1): loaded http://localhost:59168/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59168/tests/dromaeo/cssquery-mootools.html) 12:53:54 INFO - PID 3526 | RSS: Main: 173035520 12:53:54 INFO - PID 3526 | 12:56:56 INFO - PID 3526 | Cycle 1(1): loaded http://localhost:59168/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59168/tests/dromaeo/cssquery-prototype.html) 12:56:56 INFO - PID 3526 | RSS: Main: 171581440 12:56:56 INFO - PID 3526 | 13:00:00 INFO - PID 3526 | Cycle 1(1): loaded http://localhost:59168/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59168/tests/dromaeo/cssquery-yui.html) 13:00:01 INFO - PID 3526 | RSS: Main: 173740032 13:00:01 INFO - PID 3526 | 13:03:02 INFO - PID 3526 | Cycle 1(1): loaded http://localhost:59168/tests/dromaeo/cssquery-yui.html (next: http://localhost:59168/tests/dromaeo/cssquery-dojo.html) 13:03:03 INFO - PID 3526 | RSS: Main: 174280704 13:03:03 INFO - PID 3526 | 13:03:03 INFO - PID 3526 | __start_tp_report 13:03:03 INFO - PID 3526 | _x_x_mozilla_page_load 13:03:03 INFO - PID 3526 | _x_x_mozilla_page_load_details 13:03:03 INFO - PID 3526 | |i|pagename|runs| 13:03:03 INFO - PID 3526 | |0;dojo.html;3165.8575364108606;3168.7465002799763;3192.122166404239;3207.887723929663;3256.1859535116223;6207.782727604524;6257.4368306852375;6290.339514350996;6332.968335158318;6338.397852204039;6155.199824022882;6248.250209974798;6253.218347706536;6253.687315634212;6270.561060725751;5709.971450142754;5737.225474560943;5747.453991870766;5761;5865.237519122508;5658.264425624684;5664.291963504562;5667.603267771257;5792.942070579283;5834.3873893241025;5520.558355331604;5710.229119068914;5733.684647344395;5846.064629659255;5870.236869207019;5572.944270557324;5730.971345143289;5791.855203619901;5795.3045634523705;5797.739101740432;5665.688387138747;5684.403137670568;5744.3394009689;5745.942540574582;5763.077907534816;6092.603980741282;6107.694615269219;6194.5044396448175;6201.193844800147;6263.060540918853;6689.698963546603;6690.498212634072;6696.2968888267005;6697.866042679168;6707.966460167668;6803.319668033207;6807.047013418125;6823.249442561314;6842.384185423336;6847.486438517081;6140.355262697352;6215.96892015537;6218.5647004709235;6303.086052522359;6320.93679063208;6773.593584384953;6900.206476255295;6962.060121883582;6964.512484126164;6978.267281935425;6480.222373315232;6564.277929427759;6565.80302590923;6571.507136964747;6586.473082153416;6658;6659.633720145439;6668.799936001928;6673.432758215606;6677.432418244406;6701.32986701326;6720.126383570105;6721.361470660279;6723.865522689616;6735.29279425663;6785.9321406786285;6788.932110678928;6799.93200067993;6804.251532331439;6808.523403361816;5354.678719276856;5427.3215848019;5429.511343978982;5463.590230732711;5517.172424136332;4621.376114224537;4633.513481084505;4654.534546545318;4662.067586482717;4662.137504561667;5805.535557155417;5833.270841144857;5836.533077353802;5837.854053648608;5867.823965281048;6235.657038862815;6724.361185687352;6742;6751.0886030386255;6766.18805743314;5804.158397032406;5824.621399609012;5827.912581311284;5832.300123985149;5839.240898683144;5965.403459654087;5982.0727787193055;5996;6047.576669633084;6063.7574497020505;6491.545591808623;6568.540202185801;6579;6579.407853293226;6585.308542603054;6720.798376048824;6734.3602357775935;6750.32496750321;6834.555753875982;6860.142482189726;6417.8716425672155;6422.454091402283;6488.480921526361;6503.089567460654;6516.674166291761;6533.028253083888;6599.571027883172;6613.00804879272;6621.668916554249;6640.203175618956;6819.727210911707;6824.590524568542;6870.519063665495;6887.139107611549;6919.411849992807;6515.837104072436;6524.641144737085;6547.247066587403;6596.637184954681;6647;6579.144711187515;6579.802605921638;6592.077109204811;6595.241547222131;6611.074449576967;6465.06256592782;6525.0538671893255;6525.216973962965;6529.608223506605;6532.020196970492;6617.867642647024;6639.3692599203;6666.333366663295;6698.631575263407;6727.899081513683;6369.299377068426;6384.808455746335;6395.523527586412;6475.611463312028;6485.189351331083 13:03:03 INFO - PID 3526 | |1;ext.html;757.9924200757994;821.7082935557877;849.3799526345765;1120.6133883810082;1158.577119351437;30605.387892242168;41686.37470437942;42511.57488425111;42512.14975700484;42558.787206064;42386.364204536956;42432.57567424325;42625;42720.786396068055;42801.57198428023;7525.586092764882;7706.460547761659;7708.57602831844;7708.768736937873;7716.575588342639;7598.73404430845;7648.464607477451;7649.04386951631;7680.500767450126;7704.383649308042;7098.964505177467;7599.278068583503;7692.730754423596;7695;7698;7621.047369078865;7632.961835190817;7635.312821846061;7694.384449244047;7699;7483.9251607483775;7590.354819840265;7597.658105385271;7597.658105385271;7598.430117741136;38119;38369;38371.232575348615;38403.8079809602;38425.615743842485;3598.046517672819;3598.1544337080763;3598.6941110005682;3619.0771353305004;3629.981850090759;30275.545866812015;30539.389212215632;30686.693133068606;30724.69275307263;30755.846220768977;4903.289023091632;4926.014797040606;4952.777125029383;4993.925091123636;5021.748912554358;180322;181439;181908;182348;182517.0874145634;47689.5231047692;47879.28181077287;48215.758921205874;48307;48402.5159748398;50161;50724.74637626788;50740.746296268284;50782.74608626933;50808;14743.262836858328;15195;15213.61965950838;15214.315355809014;15219.391224350902;12213.45039473226;12237.449314780857;12243;12282.324472154118;12287.078469114673;12464.189827661174;12702.936485317514;14291.642708932151;14378.28108594566;14434.639134021523;5270.92093618596;5790.0627573250595;5888.558358123158;5963.373845746221;5970.641761494324;966.5795379010153;981.293468702533;985.9901400986042;994.7015895231401;1004.4425343934041;22969;23019.654705179437;23048.07807687707;23171.189008384627;23227.651585226235;1084.7505073833063;1087.7063192937862;1093.240198062355;1099.6755956992706;1117.754094099297;12242.81635775464;12268.570600029134;12322.753544929052;12356.505739770488;12390;226649;227123;227947;228081;228409;52161.73919130456;52179;52384.738076309375;52438.47561524412;52849.735751321765;1359.4698067753584;1425.5937057938438;1428.357239242345;1428.6714055767231;1432.10493441599;22128;22141.446463838533;22283.331500055025;22514.887425562767;22737.772622273566;36926.815365923;36952.445713313784;36971;37008;37142.25715485729;177866;178483;178679;179116;179191;32733.345333093683;33019;33026.83486582552;33036.50445243275;33042.33915321632;42072.579274206866;47860.28209576929;48944.75527622339;48967.75516122397;48985;34656.82671586626;34867.302653947285;35336.82331588326;35909.46135807913;36267.81866090652;34361.31277374489;34475.82762086173;34741;35713;35910.102247444025;36203.81898090493;37041.81479092587;37307.44038839474;37423.43864841975;37440.6255937448;33189;33204.335913282084;33636.32727345488;33957;34188;24919.252422427355;25209.109316800947;25508.23475295744;25569.48861022806;25705.871470642527 13:03:03 INFO - PID 3526 | |2;jquery.html;561.5423429904628;579.3048341989611;580.8925348810468;594.3165359836188;606.7936901453505;21996.34010979671;22782.430439238986;22850.200242991497;22857.97139128741;22871;25917.87041064792;26288.737112628867;26334;26427.339316517096;26513.07204247847;6435.935640643592;6608.9339106608795;6618.106555615002;6620.702068406909;6642.368974947372;5867.442592953684;6400.51996100292;6436.549441539094;6445.645489498099;6498.382653647919;6402.583832050902;6428.517861160408;6434.613923164602;6437.839054023663;6439.935600644004;6416.037594360837;6416.935830641681;6424.100625912376;6427;6430.260520040185;5954.315253745832;5992.34084250732;6011.12838638395;6013.789517366915;6015.218021657201;28981.71018289811;29470.85264573685;30076.548851767242;30549.08352749421;30921.072367829;1897.3738666240108;1923.3172223860468;1925.1817977359567;1925.922963081475;1926.8940208288539;19040.238390464503;19525;19571.90214048935;19654.606907861904;19721.21115155392;2320.9883950580306;2367.242482405631;2378.9167379141645;2381.5594115088766;2400.8919598618104;142303;145945.27027364902;146046;146092.26953865268;146733;14942.626434339229;15296;15373.9231303845;15604.21978901073;15644.765328520081;24004;24025.27924162278;24041.158559450687;24077.879610601834;24161.51676966486;9878.950605246928;10058.239808033564;10197.082262596401;10454;10509.527071281811;8145.714899978472;9465.52672366379;9547.52262386878;9561.808763824685;9619.51902404877;8504.829903401896;9606.519674016272;9707.369021013752;9736.610535578639;9767.413955162712;2648.9735850938882;2731.4673638640274;2819.745213380067;2845.378134463336;2892.146816689082;881.2377293640955;907.4464576608264;963.2871674960619;974.055166488505;977.6578197630914;18652.44042678722;18846.246150154115;18998.620027599645;19033.80966190348;25487.490250194893;1025.7743296474764;1036.108946306176;1039.896010398954;1040.557762950743;1052.726291164303;11014.50434730423;11046.502907369188;11067.944660276647;11589.652310430532;11712.414379281172;524408;530764;546060;546443;549644;125686;127994.36002819927;130068.99999999811;131423.34288328496;132181.33909330392;1272.5546058879318;1285.4858056777364;1310.351376068852;1323.9867601324056;1329.3884812985941;18549.721754173428;18600.34898778536;18790.154443050367;18811.153498092892;19064.142113604645;57476;58729.70635146797;58791;59945.10082348681;60710;76968.61515692386;78324.60837696004;79235.60382198283;82656;82704.17295826964;16423.42518011864;17181.140942953054;18049;21091.8945405272;21964.670529941744;19483.902580487007;20057.598848023248;24747.62878556787;24893.626595601443;24970.750292496843;18640.906795465937;18896.149673265012;19044.8095519043;19208.423747287605;19405.611887762447;17345.56636084108;17801.46595602134;17811.84223025499;18199.181036853108;18284.360047398277;18642.44072677822;19436;19503.219871204994;19917.30289439863;19922;20214.79785202188;20818.375448736562;20983;21129.26047588327;21164.576708465436;18931.526711831764;19135.808641913958;19156.425307240806;19170.424887253404;19314.613707726046 13:03:03 INFO - PID 3526 | |3;mootools.html;1296.7212049409377;1366.3714691242028;1380.5237193168352;1385.8544852790462;1393.6933874547597;13670.453181872726;14202.857971420282;14268.643283917907;14278.71442571148;14300.642483937907;13807.309634518258;14809.481668141618;14833.332500037497;14836.332365043572;14840.257987100656;2545.1473756291666;2582.2382397192778;2584.987075064622;2585.6767904012;2586.9741302587017;2619.253512748867;2695.9191224263304;2739.328864428218;2755.256080858167;2758.0760445250767;2688.421989272306;2697.568389057751;2716.4295497945454;2764.889404423831;2765.4745598336353;2615.555355589545;2636.894524219029;2674.9197524074307;2676.210517897215;2676.4647070585766;2584.4831033793316;2629.4346715456268;2635.82867113637;2639.3797457597643;2639.8680065996623;13569.59291221255;13660.726785464236;13817.101888377223;13831.79252311216;13835.446582136703;7689.807754806118;8003.599820009034;8037.075736290343;8081.838363232702;8085.555294458803;4848.466668666444;4954.8761280968265;4982.551570358685;5063.974680126613;5076.492350764931;1769.973450398245;1773.8492228160621;1786.8034516203206;1823.6990896502061;1826.5022681401256;22381.888090559605;22489.662655060187;22501.437464063365;22513.09947602094;22594.54810903773;7093.92906070943;7118.644067796589;7302.087239095114;7414.851702965911;7440.4791664584045;15854.682906341808;15969.920150399174;15971.361145554049;15994.280257388446;16009.759853602205;7652.885206721904;7865.488743231672;7882.566458844819;7883.802904927308;7888.566128862968;5652.434756524397;6408.871822563523;6443.420092191725;7258;7309.049823522908;5025.422076461254;5145.434002259748;5170.457102004236;5182.455842136521;5228.712420816815;1525.0773282164353;1530.8622223999894;1565.9508129552999;1575.2911189964332;1585.7621356796574;864.9610767515477;889.088684098791;894.7271082319825;907.9921287371013;913.2602591900582;7344.26557344272;7345.55926644403;7377.188509263977;7435.934530253082;7442.388172594111;710.8791505444087;948.0993056596261;985.556499575194;996.4270544436832;998.0867506231716;5125.1799712045895;5262.105442074857;5365;5381.919271210935;5426.538744206787;18690.252389904523;18693.439196824118;18954.14706338218;19519;19528.414147575597;18498.982555959283;19024.334148304744;19147.712784308245;19170.71243931342;19268.710969335472;10703.357798532114;10784.13726901846;10815.351078935131;11055.0603198729;11073.726521450137;8007.399445041528;8132.105468398353;8244.299234564889;8332.33341332704;8360.205780450848;16559.337626494806;16560.917195413946;16626.334946602;16681.74977375316;16907.154642267593;17179;17196.226169822137;17278.22247998818;17655.558611034827;17721;11530.308181509135;11835.112366572714;11842.881571184178;11863.703407414883;11865.406729663655;13080.542181023618;13250.271235081778;13412.32938353098;13465.596032119052;13549.525766598123;10676.199285053497;10845.295055821347;10907.127429805756;10943.28868623537;11042.834357484804;10714.196435267542;10784.514696838818;10889.292196007234;10939.343639381663;10981.17641176931;9628.614855405705;9634.13292803651;9850.704478865646;9851.507424628884;9857.11285984265;7209.963950180216;7275.417966562556;7370.557766534025;7390.704371825067;7408.888866666896;7648.655810488653;7727.536347818924;7755.3020228177475;7792.181820908723;7884.526928384315 13:03:03 INFO - PID 3526 | |4;prototype.html;459.87353477793624;479.95200479952007;498.34716521357024;500.81219542671494;502.0636512903434;6084.604500707456;6175.598586091901;6229.813105606828;6270.341614130519;6288.465480434157;5711.400302968191;6024;6051.152838602599;6064.969675151629;6070.332263451028;4819.228923372261;4851.924383517844;4867.634927380443;4900.705957642535;4948.703077815325;4740.644451666121;4867.2942423348595;4870.65905386623;4892.241702536095;4938.901221975559;4809.470958194614;4838.927416088762;4863.635227357945;4870.441458733207;4896.2410826321975;4829.082474329866;4831.178699621073;4881.341018962466;4906;4917.385326834164;4325.5025672047805;4356.782160891975;4358.629516491102;4374.365716971053;4427.225235583792;5972.4027597240365;6017.578769486137;6029.276486821565;6034.879302413971;6040.275166979991;5556;5589.105743081128;5600.803971860965;5601.243832082658;5618.15727640853;6412.461009357768;6428.517861160385;6447.516436267299;6450.322716114787;6451.419372256472;4701.811927522895;4738.881527961794;4746.833860814855;4762.976185119087;4771.594414474774;6691.59850408977;6718.361755633207;6754.155730533684;6773.559718618274;6846.383825455733;4834.419869615669;4873.926891096637;4875.341828853059;4910.877228069256;4944.728039957765;4949.282354058641;4950.579200767975;4975.477574854661;5003.624728145403;5020.523050310215;5598.804041858597;5601.971990140023;5623.381428042912;5654.943450565442;5687.203791469197;6479.773207937699;6528.51036172289;6543.967280163569;6584.209894812653;6603.45309939653;1616.5473667373155;1624.293432356905;1672.6403823178075;1712.332190445727;1729.161356741979;1164.8078067118831;1186.5194596188558;1189.690680423079;1194.3669854976877;1199.7060720123625;5700.458456446631;5730.942690573124;5737.454941780524;5751.654900705971;5757.078867381283;6553.148090748172;6660.733570657119;6674.399304062657;6703.916198548624;6711.664416779142;5692.658440493584;5746.195532625436;5763.221965034751;5774.509166720875;5814.389489103668;3516.7889926604485;3561.857525698943;3572.1783989682517;3585.2112535242727;3587.8744243951337;6419.646919419383;6420.229572451242;6451.903221451682;6475.417212450902;6513.902291465632;6793.252742198453;6829.214640316427;6847.589144651337;6888.242293347727;6890.414314783199;6404.238834320599;6458.257300410419;6458.644774537445;6468.288488266287;6513.316101809337;6665.166854143232;6667.533272670867;6687.598744075371;6731.293214212437;6734.797956061326;6564.179477565304;6566.421300391394;6569.540132190701;6583.341665833378;6625.768098116543;6366.9044964326495;6380;6492.285848556749;6503.512236582181;6513.511486638616;4914.8771280718265;4940.75296235194;4965.875853103701;4972.204447288561;4974.552290293891;6429.2284925807335;6585.473162146921;6689.431398331197;6785.423239024541;6811.421029212573;6300.0154951190225;6366.713497892513;6448.9032664511005;6481.157449531531;6483.98996311145;6409.35906409374;6428;6452.451541619015;6483.448906842783;6502.772402965874;6215.316315205414;6342.175517182736;6362.809115726535;6366.586171898812;6409.134766806422;6384.489240860813;6478.2550006749825;6479.676016199265;6548.934510654833;6576.210854697276;6289.245290565162;6294.590851594815;6331.176946996861;6337.524685648503;6342.7145778441 13:03:03 INFO - PID 3526 | |5;yui.html;912.0605776050666;913.3789023464044;935.5696379665353;946.6781294359917;947.9336446448747;18708.719369209455;19608.117634706436;19659.11533980971;19667.704984425243;19686.212551497923;17493.650126997454;17514.21186046628;18422.447326580223;22280.220192293273;22291.21980730675;1028.7325295423207;1056.3556230699267;1056.4136904018267;1056.6882769582953;1057.0961827637336;1004.4826914139225;1047.7275908263869;1049.1344640671439;1052.747340638245;1056.27645063132;1101.0420933787616;1101.8402331661905;1112.5327362507767;1113.6158025481195;1114.9275297105703;1088.8911108889126;1095.649392194498;1099.5272033025797;1100.0429626225155;1109.1071687291726;815.3803109636659;815.955122468264;817.3052905030677;817.811903262253;818.0388044048219;17849.464516064538;18552.16515256803;20555.89722051395;20830.27094051701;20888.895555522126;22073.889630551905;22145.048619369692;22497;22497.55004899909;22562.21032263879;16876.493705188863;16977.66044679112;17016.40442584516;17035.40376086831;17189.140542972928;3532.5231093802263;3552.964470355315;3556.78659280444;3557.004038869121;3557.1640664443835;129458;130606;131140;131306;135105.324473377;16796.748048779278;17265.39571115005;17656.911715441598;17808.287668493373;17840;30850;32358.83820580882;32380.514292285636;32451.837740811145;32735.50896736551;8457.365697572706;8511.57442127891;8640.654373825102;8659.610317535726;8738.30093592511;3981.7810020448574;4082.3672330788777;4098.118904435561;4106.691998100155;4107.260693075275;4987.077390682735;4999.850004499871;5107.080725469451;5108.131617625013;5114;937.3672770879629;942.966996155145;960.2845879819579;963.272729089533;963.5952899782109;656.6290046123925;673.5487223560128;682.2324884504932;685.6503183376514;691.7025678957996;11271.38007409584;11367;11457.19799614019;11694.94152529249;12090.153689241668;678.3284548297249;686.3891136888174;688.3460712323313;692.9376356127973;706.8939659051183;5139.254808052811;5165.457626949192;5176.637635365563;5192.792288308501;5242.68543887368;100437;101477;102173;102507.4874625622;103560.48219758853;89770;90385;92217;92265.53867230621;93157.53421232986;80400.59799701082;81636.18363816285;82428.58785706032;83191;84893.15106848853;11806.586769463027;12392.81410778821;12659.493620255455;12880.677983050498;13122.540711075066;35390.64609353874;35488;35736.821315894296;36138.638613613526;37940.241195176495;64422.67788661027;67297;68024.3197568018;69735;70760.64619676869;18598.81401186025;19689.70465442991;19713.802861971195;19763.703544447133;19953.800461995776;18707;18801.341953031577;18830.717539237197;20048.097835597142;21009.57980840405;18715.812841871953;18799.90600046991;18945.810541894403;19461.318853840046;19736;19405.32081377145;19644.705329419783;19825.90087049613;19899.303524376577;20252.898735506227;17689.203985820925;17912.462626121236;18232;18334.449966501026;18952.43142705721;13025.739485210432;13230.470781168646;13418.127821691558;13472.259025753678;13761.518346857813;14460.927695361455;15135.016223945408;15333.310001050202;15544.06735595868;15556.844431555539 13:03:03 INFO - PID 3526 | __end_tp_report 13:03:03 INFO - PID 3526 | __start_cc_report 13:03:03 INFO - PID 3526 | _x_x_mozilla_cycle_collect,373 13:03:03 INFO - PID 3526 | __end_cc_report 13:03:03 INFO - PID 3526 | __startTimestamp1502136183188__endTimestamp 13:03:03 INFO - PID 3526 | 13:03:03 INFO - PID 3526 | ------- Summary: start ------- 13:03:03 INFO - PID 3526 | Number of tests: 6 13:03:03 INFO - PID 3526 | 13:03:03 INFO - PID 3526 | [#0] dojo.html Cycles:165 Average:6194.94 Median:6475.61 stddev:733.16 (11.3%) stddev-sans-first:695.84 13:03:03 INFO - PID 3526 | Values: 3165.9 3168.7 3192.1 3207.9 3256.2 6207.8 6257.4 6290.3 6333.0 6338.4 6155.2 6248.3 6253.2 6253.7 6270.6 5710.0 5737.2 5747.5 5761.0 5865.2 5658.3 5664.3 5667.6 5792.9 5834.4 5520.6 5710.2 5733.7 5846.1 5870.2 5572.9 5731.0 5791.9 5795.3 5797.7 5665.7 5684.4 5744.3 5745.9 5763.1 6092.6 6107.7 6194.5 6201.2 6263.1 6689.7 6690.5 6696.3 6697.9 6708.0 6803.3 6807.0 6823.2 6842.4 6847.5 6140.4 6216.0 6218.6 6303.1 6320.9 6773.6 6900.2 6962.1 6964.5 6978.3 6480.2 6564.3 6565.8 6571.5 6586.5 6658.0 6659.6 6668.8 6673.4 6677.4 6701.3 6720.1 6721.4 6723.9 6735.3 6785.9 6788.9 6799.9 6804.3 6808.5 5354.7 5427.3 5429.5 5463.6 5517.2 4621.4 4633.5 4654.5 4662.1 4662.1 5805.5 5833.3 5836.5 5837.9 5867.8 6235.7 6724.4 6742.0 6751.1 6766.2 5804.2 5824.6 5827.9 5832.3 5839.2 5965.4 5982.1 5996.0 6047.6 6063.8 6491.5 6568.5 6579.0 6579.4 6585.3 6720.8 6734.4 6750.3 6834.6 6860.1 6417.9 6422.5 6488.5 6503.1 6516.7 6533.0 6599.6 6613.0 6621.7 6640.2 6819.7 6824.6 6870.5 6887.1 6919.4 6515.8 6524.6 6547.2 6596.6 6647.0 6579.1 6579.8 6592.1 6595.2 6611.1 6465.1 6525.1 6525.2 6529.6 6532.0 6617.9 6639.4 6666.3 6698.6 6727.9 6369.3 6384.8 6395.5 6475.6 6485.2 13:03:03 INFO - PID 3526 | 13:03:03 INFO - PID 3526 | [#1] ext.html Cycles:180 Average:36951.08 Median:3608.89 stddev:51113.35 (1416.3%) stddev-sans-first:51184.49 13:03:03 INFO - PID 3526 | Values: 758.0 821.7 849.4 1120.6 1158.6 30605.4 41686.4 42511.6 42512.1 42558.8 42386.4 42432.6 42625.0 42720.8 42801.6 7525.6 7706.5 7708.6 7708.8 7716.6 7598.7 7648.5 7649.0 7680.5 7704.4 7099.0 7599.3 7692.7 7695.0 7698.0 7621.0 7633.0 7635.3 7694.4 7699.0 7483.9 7590.4 7597.7 7597.7 7598.4 38119.0 38369.0 38371.2 38403.8 38425.6 3598.0 3598.2 3598.7 3619.1 3630.0 30275.5 30539.4 30686.7 30724.7 30755.8 4903.3 4926.0 4952.8 4993.9 5021.7 180322.0 181439.0 181908.0 182348.0 182517.1 47689.5 47879.3 48215.8 48307.0 48402.5 50161.0 50724.7 50740.7 50782.7 50808.0 14743.3 15195.0 15213.6 15214.3 15219.4 12213.5 12237.4 12243.0 12282.3 12287.1 12464.2 12702.9 14291.6 14378.3 14434.6 5270.9 5790.1 5888.6 5963.4 5970.6 966.6 981.3 986.0 994.7 1004.4 22969.0 23019.7 23048.1 23171.2 23227.7 1084.8 1087.7 1093.2 1099.7 1117.8 12242.8 12268.6 12322.8 12356.5 12390.0 226649.0 227123.0 227947.0 228081.0 228409.0 52161.7 52179.0 52384.7 52438.5 52849.7 1359.5 1425.6 1428.4 1428.7 1432.1 22128.0 22141.4 22283.3 22514.9 22737.8 36926.8 36952.4 36971.0 37008.0 37142.3 177866.0 178483.0 178679.0 179116.0 179191.0 32733.3 33019.0 33026.8 33036.5 33042.3 42072.6 47860.3 48944.8 48967.8 48985.0 34656.8 34867.3 35336.8 35909.5 36267.8 34361.3 34475.8 34741.0 35713.0 35910.1 36203.8 37041.8 37307.4 37423.4 37440.6 33189.0 33204.3 33636.3 33957.0 34188.0 24919.3 25209.1 25508.2 25569.5 25705.9 13:03:03 INFO - PID 3526 | 13:03:03 INFO - PID 3526 | [#2] jquery.html Cycles:180 Average:37540.96 Median:2344.12 stddev:90877.06 (3876.8%) stddev-sans-first:91089.58 13:03:03 INFO - PID 3526 | Values: 561.5 579.3 580.9 594.3 606.8 21996.3 22782.4 22850.2 22858.0 22871.0 25917.9 26288.7 26334.0 26427.3 26513.1 6435.9 6608.9 6618.1 6620.7 6642.4 5867.4 6400.5 6436.5 6445.6 6498.4 6402.6 6428.5 6434.6 6437.8 6439.9 6416.0 6416.9 6424.1 6427.0 6430.3 5954.3 5992.3 6011.1 6013.8 6015.2 28981.7 29470.9 30076.5 30549.1 30921.1 1897.4 1923.3 1925.2 1925.9 1926.9 19040.2 19525.0 19571.9 19654.6 19721.2 2321.0 2367.2 2378.9 2381.6 2400.9 142303.0 145945.3 146046.0 146092.3 146733.0 14942.6 15296.0 15373.9 15604.2 15644.8 24004.0 24025.3 24041.2 24077.9 24161.5 9879.0 10058.2 10197.1 10454.0 10509.5 8145.7 9465.5 9547.5 9561.8 9619.5 8504.8 9606.5 9707.4 9736.6 9767.4 2649.0 2731.5 2819.7 2845.4 2892.1 881.2 907.4 963.3 974.1 977.7 18652.4 18846.2 18998.6 19033.8 25487.5 1025.8 1036.1 1039.9 1040.6 1052.7 11014.5 11046.5 11067.9 11589.7 11712.4 524408.0 530764.0 546060.0 546443.0 549644.0 125686.0 127994.4 130069.0 131423.3 132181.3 1272.6 1285.5 1310.4 1324.0 1329.4 18549.7 18600.3 18790.2 18811.2 19064.1 57476.0 58729.7 58791.0 59945.1 60710.0 76968.6 78324.6 79235.6 82656.0 82704.2 16423.4 17181.1 18049.0 21091.9 21964.7 19483.9 20057.6 24747.6 24893.6 24970.8 18640.9 18896.1 19044.8 19208.4 19405.6 17345.6 17801.5 17811.8 18199.2 18284.4 18642.4 19436.0 19503.2 19917.3 19922.0 20214.8 20818.4 20983.0 21129.3 21164.6 18931.5 19135.8 19156.4 19170.4 19314.6 13:03:03 INFO - PID 3526 | 13:03:03 INFO - PID 3526 | [#3] mootools.html Cycles:180 Average:8787.81 Median:2601.26 stddev:5886.76 (226.3%) stddev-sans-first:5876.36 13:03:03 INFO - PID 3526 | Values: 1296.7 1366.4 1380.5 1385.9 1393.7 13670.5 14202.9 14268.6 14278.7 14300.6 13807.3 14809.5 14833.3 14836.3 14840.3 2545.1 2582.2 2585.0 2585.7 2587.0 2619.3 2695.9 2739.3 2755.3 2758.1 2688.4 2697.6 2716.4 2764.9 2765.5 2615.6 2636.9 2674.9 2676.2 2676.5 2584.5 2629.4 2635.8 2639.4 2639.9 13569.6 13660.7 13817.1 13831.8 13835.4 7689.8 8003.6 8037.1 8081.8 8085.6 4848.5 4954.9 4982.6 5064.0 5076.5 1770.0 1773.8 1786.8 1823.7 1826.5 22381.9 22489.7 22501.4 22513.1 22594.5 7093.9 7118.6 7302.1 7414.9 7440.5 15854.7 15969.9 15971.4 15994.3 16009.8 7652.9 7865.5 7882.6 7883.8 7888.6 5652.4 6408.9 6443.4 7258.0 7309.0 5025.4 5145.4 5170.5 5182.5 5228.7 1525.1 1530.9 1566.0 1575.3 1585.8 865.0 889.1 894.7 908.0 913.3 7344.3 7345.6 7377.2 7435.9 7442.4 710.9 948.1 985.6 996.4 998.1 5125.2 5262.1 5365.0 5381.9 5426.5 18690.3 18693.4 18954.1 19519.0 19528.4 18499.0 19024.3 19147.7 19170.7 19268.7 10703.4 10784.1 10815.4 11055.1 11073.7 8007.4 8132.1 8244.3 8332.3 8360.2 16559.3 16560.9 16626.3 16681.7 16907.2 17179.0 17196.2 17278.2 17655.6 17721.0 11530.3 11835.1 11842.9 11863.7 11865.4 13080.5 13250.3 13412.3 13465.6 13549.5 10676.2 10845.3 10907.1 10943.3 11042.8 10714.2 10784.5 10889.3 10939.3 10981.2 9628.6 9634.1 9850.7 9851.5 9857.1 7210.0 7275.4 7370.6 7390.7 7408.9 7648.7 7727.5 7755.3 7792.2 7884.5 13:03:03 INFO - PID 3526 | 13:03:03 INFO - PID 3526 | [#4] prototype.html Cycles:180 Average:5439.74 Median:6037.58 stddev:1542.50 (25.5%) stddev-sans-first:1500.86 13:03:03 INFO - PID 3526 | Values: 459.9 480.0 498.3 500.8 502.1 6084.6 6175.6 6229.8 6270.3 6288.5 5711.4 6024.0 6051.2 6065.0 6070.3 4819.2 4851.9 4867.6 4900.7 4948.7 4740.6 4867.3 4870.7 4892.2 4938.9 4809.5 4838.9 4863.6 4870.4 4896.2 4829.1 4831.2 4881.3 4906.0 4917.4 4325.5 4356.8 4358.6 4374.4 4427.2 5972.4 6017.6 6029.3 6034.9 6040.3 5556.0 5589.1 5600.8 5601.2 5618.2 6412.5 6428.5 6447.5 6450.3 6451.4 4701.8 4738.9 4746.8 4763.0 4771.6 6691.6 6718.4 6754.2 6773.6 6846.4 4834.4 4873.9 4875.3 4910.9 4944.7 4949.3 4950.6 4975.5 5003.6 5020.5 5598.8 5602.0 5623.4 5654.9 5687.2 6479.8 6528.5 6544.0 6584.2 6603.5 1616.5 1624.3 1672.6 1712.3 1729.2 1164.8 1186.5 1189.7 1194.4 1199.7 5700.5 5730.9 5737.5 5751.7 5757.1 6553.1 6660.7 6674.4 6703.9 6711.7 5692.7 5746.2 5763.2 5774.5 5814.4 3516.8 3561.9 3572.2 3585.2 3587.9 6419.6 6420.2 6451.9 6475.4 6513.9 6793.3 6829.2 6847.6 6888.2 6890.4 6404.2 6458.3 6458.6 6468.3 6513.3 6665.2 6667.5 6687.6 6731.3 6734.8 6564.2 6566.4 6569.5 6583.3 6625.8 6366.9 6380.0 6492.3 6503.5 6513.5 4914.9 4940.8 4965.9 4972.2 4974.6 6429.2 6585.5 6689.4 6785.4 6811.4 6300.0 6366.7 6448.9 6481.2 6484.0 6409.4 6428.0 6452.5 6483.4 6502.8 6215.3 6342.2 6362.8 6366.6 6409.1 6384.5 6478.3 6479.7 6548.9 6576.2 6289.2 6294.6 6331.2 6337.5 6342.7 13:03:03 INFO - PID 3526 | 13:03:03 INFO - PID 3526 | [#5] yui.html Cycles:180 Average:23427.24 Median:19926.55 stddev:31312.46 (157.1%) stddev-sans-first:31354.65 13:03:03 INFO - PID 3526 | Values: 912.1 913.4 935.6 946.7 947.9 18708.7 19608.1 19659.1 19667.7 19686.2 17493.7 17514.2 18422.4 22280.2 22291.2 1028.7 1056.4 1056.4 1056.7 1057.1 1004.5 1047.7 1049.1 1052.7 1056.3 1101.0 1101.8 1112.5 1113.6 1114.9 1088.9 1095.6 1099.5 1100.0 1109.1 815.4 816.0 817.3 817.8 818.0 17849.5 18552.2 20555.9 20830.3 20888.9 22073.9 22145.0 22497.0 22497.6 22562.2 16876.5 16977.7 17016.4 17035.4 17189.1 3532.5 3553.0 3556.8 3557.0 3557.2 129458.0 130606.0 131140.0 131306.0 135105.3 16796.7 17265.4 17656.9 17808.3 17840.0 30850.0 32358.8 32380.5 32451.8 32735.5 8457.4 8511.6 8640.7 8659.6 8738.3 3981.8 4082.4 4098.1 4106.7 4107.3 4987.1 4999.9 5107.1 5108.1 5114.0 937.4 943.0 960.3 963.3 963.6 656.6 673.5 682.2 685.7 691.7 11271.4 11367.0 11457.2 11694.9 12090.2 678.3 686.4 688.3 692.9 706.9 5139.3 5165.5 5176.6 5192.8 5242.7 100437.0 101477.0 102173.0 102507.5 103560.5 89770.0 90385.0 92217.0 92265.5 93157.5 80400.6 81636.2 82428.6 83191.0 84893.2 11806.6 12392.8 12659.5 12880.7 13122.5 35390.6 35488.0 35736.8 36138.6 37940.2 64422.7 67297.0 68024.3 69735.0 70760.6 18598.8 19689.7 19713.8 19763.7 19953.8 18707.0 18801.3 18830.7 20048.1 21009.6 18715.8 18799.9 18945.8 19461.3 19736.0 19405.3 19644.7 19825.9 19899.3 20252.9 17689.2 17912.5 18232.0 18334.4 18952.4 13025.7 13230.5 13418.1 13472.3 13761.5 14460.9 15135.0 15333.3 15544.1 15556.8 13:03:03 INFO - PID 3526 | -------- Summary: end -------- 13:03:03 INFO - PID 3526 | 13:03:03 INFO - TEST-INFO | 3526: exit 0 13:03:03 INFO - TEST-OK | dromaeo_css | took 1113967ms 13:03:03 INFO - TEST-START | kraken 13:03:03 INFO - Initialising browser for kraken test... 13:03:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59168/getInfo.html -profile /tmp/tmp_alOAj/profile 13:03:03 INFO - TEST-INFO | started process 3884 (/builds/slave/test/build/application/firefox/firefox http://localhost:59168/getInfo.html) 13:03:09 INFO - TEST-INFO | 3884: exit 0 13:03:09 INFO - Browser initialized. 13:03:09 INFO - Running cycle 1/1 for kraken test... 13:03:09 INFO - TEST-INFO | started process 4091 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_alOAj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:03:10 INFO - PID 4091 | 13:03:10 INFO - PID 4091 | (/builds/slave/test/build/application/firefox/firefox:4140): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:03:10 INFO - PID 4091 | 13:03:20 INFO - PID 4091 | RSS: Main: 171237376 13:03:20 INFO - PID 4091 | 13:03:23 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59168/tests/kraken/driver.html?testName=audio-beat-detection) 13:03:24 INFO - PID 4091 | RSS: Main: 176025600 13:03:24 INFO - PID 4091 | 13:03:27 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59168/tests/kraken/driver.html?testName=audio-dft) 13:03:27 INFO - PID 4091 | RSS: Main: 176607232 13:03:27 INFO - PID 4091 | 13:03:30 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59168/tests/kraken/driver.html?testName=audio-fft) 13:03:31 INFO - PID 4091 | RSS: Main: 171143168 13:03:31 INFO - PID 4091 | 13:03:33 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59168/tests/kraken/driver.html?testName=audio-oscillator) 13:03:34 INFO - PID 4091 | RSS: Main: 171880448 13:03:34 INFO - PID 4091 | 13:03:36 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59168/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:03:36 INFO - PID 4091 | RSS: Main: 172494848 13:03:36 INFO - PID 4091 | 13:03:39 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59168/tests/kraken/driver.html?testName=imaging-darkroom) 13:03:40 INFO - PID 4091 | RSS: Main: 174821376 13:03:40 INFO - PID 4091 | 13:03:43 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59168/tests/kraken/driver.html?testName=imaging-desaturate) 13:03:44 INFO - PID 4091 | RSS: Main: 174919680 13:03:44 INFO - PID 4091 | 13:03:47 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59168/tests/kraken/driver.html?testName=json-parse-financial) 13:03:48 INFO - PID 4091 | RSS: Main: 174727168 13:03:48 INFO - PID 4091 | 13:03:49 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59168/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:03:50 INFO - PID 4091 | RSS: Main: 174968832 13:03:50 INFO - PID 4091 | 13:03:52 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59168/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:03:52 INFO - PID 4091 | RSS: Main: 175046656 13:03:52 INFO - PID 4091 | 13:03:54 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59168/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:03:55 INFO - PID 4091 | RSS: Main: 172085248 13:03:55 INFO - PID 4091 | 13:03:58 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:03:58 INFO - PID 4091 | RSS: Main: 173150208 13:03:58 INFO - PID 4091 | 13:04:01 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:04:02 INFO - PID 4091 | RSS: Main: 172933120 13:04:02 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | Cycle 1(1): loaded http://localhost:59168/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59168/tests/kraken/driver.html?testName=ai-astar) 13:04:04 INFO - PID 4091 | RSS: Main: 174329856 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | __start_tp_report 13:04:04 INFO - PID 4091 | _x_x_mozilla_page_load 13:04:04 INFO - PID 4091 | _x_x_mozilla_page_load_details 13:04:04 INFO - PID 4091 | |i|pagename|runs| 13:04:04 INFO - PID 4091 | |0;ai-astar;105;108;100;106;108;109;110;99;109;111 13:04:04 INFO - PID 4091 | |1;audio-beat-detection;143;123;143;182;123;148;146;120;165;119 13:04:04 INFO - PID 4091 | |2;audio-dft;165;164;164;162;163;163;166;165;167;163 13:04:04 INFO - PID 4091 | |3;audio-fft;74;75;74;177;74;78;74;73;156;76 13:04:04 INFO - PID 4091 | |4;audio-oscillator;80;80;135;78;85;79;125;79;79;122 13:04:04 INFO - PID 4091 | |5;imaging-gaussian-blur;114;114;114;114;114;114;114;114;115;115 13:04:04 INFO - PID 4091 | |6;imaging-darkroom;157;150;150;149;152;150;151;151;151;150 13:04:04 INFO - PID 4091 | |7;imaging-desaturate;95;93;98;94;94;94;94;95;94;94 13:04:04 INFO - PID 4091 | |8;json-parse-financial;56;57;54;54;54;60;56;55;54;54 13:04:04 INFO - PID 4091 | |9;json-stringify-tinderbox;54;54;54;54;54;54;54;53;56;54 13:04:04 INFO - PID 4091 | |10;stanford-crypto-aes;78;77;78;97;86;76;76;88;79;89 13:04:04 INFO - PID 4091 | |11;stanford-crypto-ccm;126;128;123;126;131;124;124;136;127;166 13:04:04 INFO - PID 4091 | |12;stanford-crypto-pbkdf2;178;170;177;176;180;192;176;180;180;180 13:04:04 INFO - PID 4091 | |13;stanford-crypto-sha256-iterative;68;66;67;68;65;65;67;67;67;64 13:04:04 INFO - PID 4091 | __end_tp_report 13:04:04 INFO - PID 4091 | __start_cc_report 13:04:04 INFO - PID 4091 | _x_x_mozilla_cycle_collect,949 13:04:04 INFO - PID 4091 | __end_cc_report 13:04:04 INFO - PID 4091 | __startTimestamp1502136244569__endTimestamp 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | ------- Summary: start ------- 13:04:04 INFO - PID 4091 | Number of tests: 14 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#0] ai-astar Cycles:10 Average:106.50 Median:109.00 stddev:4.09 (3.8%) stddev-sans-first:4.30 13:04:04 INFO - PID 4091 | Values: 105.0 108.0 100.0 106.0 108.0 109.0 110.0 99.0 109.0 111.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:144.50 stddev:20.85 (14.4%) stddev-sans-first:22.10 13:04:04 INFO - PID 4091 | Values: 143.0 123.0 143.0 182.0 123.0 148.0 146.0 120.0 165.0 119.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#2] audio-dft Cycles:10 Average:164.20 Median:164.50 stddev:1.55 (0.9%) stddev-sans-first:1.62 13:04:04 INFO - PID 4091 | Values: 165.0 164.0 164.0 162.0 163.0 163.0 166.0 165.0 167.0 163.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#3] audio-fft Cycles:10 Average:93.10 Median:74.00 stddev:39.03 (52.7%) stddev-sans-first:40.78 13:04:04 INFO - PID 4091 | Values: 74.0 75.0 74.0 177.0 74.0 78.0 74.0 73.0 156.0 76.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#4] audio-oscillator Cycles:10 Average:94.20 Median:79.00 stddev:23.17 (29.3%) stddev-sans-first:23.99 13:04:04 INFO - PID 4091 | Values: 80.0 80.0 135.0 78.0 85.0 79.0 125.0 79.0 79.0 122.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:04:04 INFO - PID 4091 | Values: 114.0 114.0 114.0 114.0 114.0 114.0 114.0 114.0 115.0 115.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.00 stddev:2.23 (1.5%) stddev-sans-first:0.88 13:04:04 INFO - PID 4091 | Values: 157.0 150.0 150.0 149.0 152.0 150.0 151.0 151.0 151.0 150.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:1.35 (1.4%) stddev-sans-first:1.42 13:04:04 INFO - PID 4091 | Values: 95.0 93.0 98.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.50 stddev:1.96 (3.5%) stddev-sans-first:2.06 13:04:04 INFO - PID 4091 | Values: 56.0 57.0 54.0 54.0 54.0 60.0 56.0 55.0 54.0 54.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#9] json-stringify-tinderbox Cycles:10 Average:54.10 Median:54.00 stddev:0.74 (1.4%) stddev-sans-first:0.78 13:04:04 INFO - PID 4091 | Values: 54.0 54.0 54.0 54.0 54.0 54.0 54.0 53.0 56.0 54.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:82.50 stddev:7.17 (8.7%) stddev-sans-first:7.42 13:04:04 INFO - PID 4091 | Values: 78.0 77.0 78.0 97.0 86.0 76.0 76.0 88.0 79.0 89.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#11] stanford-crypto-ccm Cycles:10 Average:131.10 Median:127.50 stddev:12.85 (10.1%) stddev-sans-first:13.50 13:04:04 INFO - PID 4091 | Values: 126.0 128.0 123.0 126.0 131.0 124.0 124.0 136.0 127.0 166.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.90 Median:180.00 stddev:5.55 (3.1%) stddev-sans-first:5.87 13:04:04 INFO - PID 4091 | Values: 178.0 170.0 177.0 176.0 180.0 192.0 176.0 180.0 180.0 180.0 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.35 (2.0%) stddev-sans-first:1.30 13:04:04 INFO - PID 4091 | Values: 68.0 66.0 67.0 68.0 65.0 65.0 67.0 67.0 67.0 64.0 13:04:04 INFO - PID 4091 | -------- Summary: end -------- 13:04:04 INFO - PID 4091 | 13:04:04 INFO - PID 4091 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 13:04:05 INFO - TEST-INFO | 4091: exit 0 13:04:05 INFO - TEST-OK | kraken | took 61366ms 13:04:05 INFO - SUITE-END | took 1175s 13:04:05 INFO - Completed test suite (00:19:35) 13:04:05 INFO - javascript benchmark 13:04:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8530.823561769039, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3165.8575364108606, 3168.7465002799763, 3192.122166404239, 3207.887723929663, 3256.1859535116223, 6207.782727604524, 6257.4368306852375, 6290.339514350996, 6332.968335158318, 6338.397852204039, 6155.199824022882, 6248.250209974798, 6253.218347706536, 6253.687315634212, 6270.561060725751, 5709.971450142754, 5737.225474560943, 5747.453991870766, 5761.0, 5865.237519122508, 5658.264425624684, 5664.291963504562, 5667.603267771257, 5792.942070579283, 5834.3873893241025, 5520.558355331604, 5710.229119068914, 5733.684647344395, 5846.064629659255, 5870.236869207019, 5572.944270557324, 5730.971345143289, 5791.855203619901, 5795.3045634523705, 5797.739101740432, 5665.688387138747, 5684.403137670568, 5744.3394009689, 5745.942540574582, 5763.077907534816, 6092.603980741282, 6107.694615269219, 6194.5044396448175, 6201.193844800147, 6263.060540918853, 6689.698963546603, 6690.498212634072, 6696.2968888267005, 6697.866042679168, 6707.966460167668, 6803.319668033207, 6807.047013418125, 6823.249442561314, 6842.384185423336, 6847.486438517081, 6140.355262697352, 6215.96892015537, 6218.5647004709235, 6303.086052522359, 6320.93679063208, 6773.593584384953, 6900.206476255295, 6962.060121883582, 6964.512484126164, 6978.267281935425, 6480.222373315232, 6564.277929427759, 6565.80302590923, 6571.507136964747, 6586.473082153416, 6658.0, 6659.633720145439, 6668.799936001928, 6673.432758215606, 6677.432418244406, 6701.32986701326, 6720.126383570105, 6721.361470660279, 6723.865522689616, 6735.29279425663, 6785.9321406786285, 6788.932110678928, 6799.93200067993, 6804.251532331439, 6808.523403361816, 5354.678719276856, 5427.3215848019, 5429.511343978982, 5463.590230732711, 5517.172424136332, 4621.376114224537, 4633.513481084505, 4654.534546545318, 4662.067586482717, 4662.137504561667, 5805.535557155417, 5833.270841144857, 5836.533077353802, 5837.854053648608, 5867.823965281048, 6235.657038862815, 6724.361185687352, 6742.0, 6751.0886030386255, 6766.18805743314, 5804.158397032406, 5824.621399609012, 5827.912581311284, 5832.300123985149, 5839.240898683144, 5965.403459654087, 5982.0727787193055, 5996.0, 6047.576669633084, 6063.7574497020505, 6491.545591808623, 6568.540202185801, 6579.0, 6579.407853293226, 6585.308542603054, 6720.798376048824, 6734.3602357775935, 6750.32496750321, 6834.555753875982, 6860.142482189726, 6417.8716425672155, 6422.454091402283, 6488.480921526361, 6503.089567460654, 6516.674166291761, 6533.028253083888, 6599.571027883172, 6613.00804879272, 6621.668916554249, 6640.203175618956, 6819.727210911707, 6824.590524568542, 6870.519063665495, 6887.139107611549, 6919.411849992807, 6515.837104072436, 6524.641144737085, 6547.247066587403, 6596.637184954681, 6647.0, 6579.144711187515, 6579.802605921638, 6592.077109204811, 6595.241547222131, 6611.074449576967, 6465.06256592782, 6525.0538671893255, 6525.216973962965, 6529.608223506605, 6532.020196970492, 6617.867642647024, 6639.3692599203, 6666.333366663295, 6698.631575263407, 6727.899081513683, 6369.299377068426, 6384.808455746335, 6395.523527586412, 6475.611463312028, 6485.189351331083], "value": 6140.079829905209, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.9924200757994, 821.7082935557877, 849.3799526345765, 1120.6133883810082, 1158.577119351437, 30605.387892242168, 41686.37470437942, 42511.57488425111, 42512.14975700484, 42558.787206064, 42386.364204536956, 42432.57567424325, 42625.0, 42720.786396068055, 42801.57198428023, 7525.586092764882, 7706.460547761659, 7708.57602831844, 7708.768736937873, 7716.575588342639, 7598.73404430845, 7648.464607477451, 7649.04386951631, 7680.500767450126, 7704.383649308042, 7098.964505177467, 7599.278068583503, 7692.730754423596, 7695.0, 7698.0, 7621.047369078865, 7632.961835190817, 7635.312821846061, 7694.384449244047, 7699.0, 7483.9251607483775, 7590.354819840265, 7597.658105385271, 7597.658105385271, 7598.430117741136, 38119.0, 38369.0, 38371.232575348615, 38403.8079809602, 38425.615743842485, 3598.046517672819, 3598.1544337080763, 3598.6941110005682, 3619.0771353305004, 3629.981850090759, 30275.545866812015, 30539.389212215632, 30686.693133068606, 30724.69275307263, 30755.846220768977, 4903.289023091632, 4926.014797040606, 4952.777125029383, 4993.925091123636, 5021.748912554358, 180322.0, 181439.0, 181908.0, 182348.0, 182517.0874145634, 47689.5231047692, 47879.28181077287, 48215.758921205874, 48307.0, 48402.5159748398, 50161.0, 50724.74637626788, 50740.746296268284, 50782.74608626933, 50808.0, 14743.262836858328, 15195.0, 15213.61965950838, 15214.315355809014, 15219.391224350902, 12213.45039473226, 12237.449314780857, 12243.0, 12282.324472154118, 12287.078469114673, 12464.189827661174, 12702.936485317514, 14291.642708932151, 14378.28108594566, 14434.639134021523, 5270.92093618596, 5790.0627573250595, 5888.558358123158, 5963.373845746221, 5970.641761494324, 966.5795379010153, 981.293468702533, 985.9901400986042, 994.7015895231401, 1004.4425343934041, 22969.0, 23019.654705179437, 23048.07807687707, 23171.189008384627, 23227.651585226235, 1084.7505073833063, 1087.7063192937862, 1093.240198062355, 1099.6755956992706, 1117.754094099297, 12242.81635775464, 12268.570600029134, 12322.753544929052, 12356.505739770488, 12390.0, 226649.0, 227123.0, 227947.0, 228081.0, 228409.0, 52161.73919130456, 52179.0, 52384.738076309375, 52438.47561524412, 52849.735751321765, 1359.4698067753584, 1425.5937057938438, 1428.357239242345, 1428.6714055767231, 1432.10493441599, 22128.0, 22141.446463838533, 22283.331500055025, 22514.887425562767, 22737.772622273566, 36926.815365923, 36952.445713313784, 36971.0, 37008.0, 37142.25715485729, 177866.0, 178483.0, 178679.0, 179116.0, 179191.0, 32733.345333093683, 33019.0, 33026.83486582552, 33036.50445243275, 33042.33915321632, 42072.579274206866, 47860.28209576929, 48944.75527622339, 48967.75516122397, 48985.0, 34656.82671586626, 34867.302653947285, 35336.82331588326, 35909.46135807913, 36267.81866090652, 34361.31277374489, 34475.82762086173, 34741.0, 35713.0, 35910.102247444025, 36203.81898090493, 37041.81479092587, 37307.44038839474, 37423.43864841975, 37440.6255937448, 33189.0, 33204.335913282084, 33636.32727345488, 33957.0, 34188.0, 24919.252422427355, 25209.109316800947, 25508.23475295744, 25569.48861022806, 25705.871470642527], "value": 17138.415775116133, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.5423429904628, 579.3048341989611, 580.8925348810468, 594.3165359836188, 606.7936901453505, 21996.34010979671, 22782.430439238986, 22850.200242991497, 22857.97139128741, 22871.0, 25917.87041064792, 26288.737112628867, 26334.0, 26427.339316517096, 26513.07204247847, 6435.935640643592, 6608.9339106608795, 6618.106555615002, 6620.702068406909, 6642.368974947372, 5867.442592953684, 6400.51996100292, 6436.549441539094, 6445.645489498099, 6498.382653647919, 6402.583832050902, 6428.517861160408, 6434.613923164602, 6437.839054023663, 6439.935600644004, 6416.037594360837, 6416.935830641681, 6424.100625912376, 6427.0, 6430.260520040185, 5954.315253745832, 5992.34084250732, 6011.12838638395, 6013.789517366915, 6015.218021657201, 28981.71018289811, 29470.85264573685, 30076.548851767242, 30549.08352749421, 30921.072367829, 1897.3738666240108, 1923.3172223860468, 1925.1817977359567, 1925.922963081475, 1926.8940208288539, 19040.238390464503, 19525.0, 19571.90214048935, 19654.606907861904, 19721.21115155392, 2320.9883950580306, 2367.242482405631, 2378.9167379141645, 2381.5594115088766, 2400.8919598618104, 142303.0, 145945.27027364902, 146046.0, 146092.26953865268, 146733.0, 14942.626434339229, 15296.0, 15373.9231303845, 15604.21978901073, 15644.765328520081, 24004.0, 24025.27924162278, 24041.158559450687, 24077.879610601834, 24161.51676966486, 9878.950605246928, 10058.239808033564, 10197.082262596401, 10454.0, 10509.527071281811, 8145.714899978472, 9465.52672366379, 9547.52262386878, 9561.808763824685, 9619.51902404877, 8504.829903401896, 9606.519674016272, 9707.369021013752, 9736.610535578639, 9767.413955162712, 2648.9735850938882, 2731.4673638640274, 2819.745213380067, 2845.378134463336, 2892.146816689082, 881.2377293640955, 907.4464576608264, 963.2871674960619, 974.055166488505, 977.6578197630914, 18652.44042678722, 18846.246150154115, 18998.620027599645, 19033.80966190348, 25487.490250194893, 1025.7743296474764, 1036.108946306176, 1039.896010398954, 1040.557762950743, 1052.726291164303, 11014.50434730423, 11046.502907369188, 11067.944660276647, 11589.652310430532, 11712.414379281172, 524408.0, 530764.0, 546060.0, 546443.0, 549644.0, 125686.0, 127994.36002819927, 130068.99999999811, 131423.34288328496, 132181.33909330392, 1272.5546058879318, 1285.4858056777364, 1310.351376068852, 1323.9867601324056, 1329.3884812985941, 18549.721754173428, 18600.34898778536, 18790.154443050367, 18811.153498092892, 19064.142113604645, 57476.0, 58729.70635146797, 58791.0, 59945.10082348681, 60710.0, 76968.61515692386, 78324.60837696004, 79235.60382198283, 82656.0, 82704.17295826964, 16423.42518011864, 17181.140942953054, 18049.0, 21091.8945405272, 21964.670529941744, 19483.902580487007, 20057.598848023248, 24747.62878556787, 24893.626595601443, 24970.750292496843, 18640.906795465937, 18896.149673265012, 19044.8095519043, 19208.423747287605, 19405.611887762447, 17345.56636084108, 17801.46595602134, 17811.84223025499, 18199.181036853108, 18284.360047398277, 18642.44072677822, 19436.0, 19503.219871204994, 19917.30289439863, 19922.0, 20214.79785202188, 20818.375448736562, 20983.0, 21129.26047588327, 21164.576708465436, 18931.526711831764, 19135.808641913958, 19156.425307240806, 19170.424887253404, 19314.613707726046], "value": 12450.399273842857, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.7212049409377, 1366.3714691242028, 1380.5237193168352, 1385.8544852790462, 1393.6933874547597, 13670.453181872726, 14202.857971420282, 14268.643283917907, 14278.71442571148, 14300.642483937907, 13807.309634518258, 14809.481668141618, 14833.332500037497, 14836.332365043572, 14840.257987100656, 2545.1473756291666, 2582.2382397192778, 2584.987075064622, 2585.6767904012, 2586.9741302587017, 2619.253512748867, 2695.9191224263304, 2739.328864428218, 2755.256080858167, 2758.0760445250767, 2688.421989272306, 2697.568389057751, 2716.4295497945454, 2764.889404423831, 2765.4745598336353, 2615.555355589545, 2636.894524219029, 2674.9197524074307, 2676.210517897215, 2676.4647070585766, 2584.4831033793316, 2629.4346715456268, 2635.82867113637, 2639.3797457597643, 2639.8680065996623, 13569.59291221255, 13660.726785464236, 13817.101888377223, 13831.79252311216, 13835.446582136703, 7689.807754806118, 8003.599820009034, 8037.075736290343, 8081.838363232702, 8085.555294458803, 4848.466668666444, 4954.8761280968265, 4982.551570358685, 5063.974680126613, 5076.492350764931, 1769.973450398245, 1773.8492228160621, 1786.8034516203206, 1823.6990896502061, 1826.5022681401256, 22381.888090559605, 22489.662655060187, 22501.437464063365, 22513.09947602094, 22594.54810903773, 7093.92906070943, 7118.644067796589, 7302.087239095114, 7414.851702965911, 7440.4791664584045, 15854.682906341808, 15969.920150399174, 15971.361145554049, 15994.280257388446, 16009.759853602205, 7652.885206721904, 7865.488743231672, 7882.566458844819, 7883.802904927308, 7888.566128862968, 5652.434756524397, 6408.871822563523, 6443.420092191725, 7258.0, 7309.049823522908, 5025.422076461254, 5145.434002259748, 5170.457102004236, 5182.455842136521, 5228.712420816815, 1525.0773282164353, 1530.8622223999894, 1565.9508129552999, 1575.2911189964332, 1585.7621356796574, 864.9610767515477, 889.088684098791, 894.7271082319825, 907.9921287371013, 913.2602591900582, 7344.26557344272, 7345.55926644403, 7377.188509263977, 7435.934530253082, 7442.388172594111, 710.8791505444087, 948.0993056596261, 985.556499575194, 996.4270544436832, 998.0867506231716, 5125.1799712045895, 5262.105442074857, 5365.0, 5381.919271210935, 5426.538744206787, 18690.252389904523, 18693.439196824118, 18954.14706338218, 19519.0, 19528.414147575597, 18498.982555959283, 19024.334148304744, 19147.712784308245, 19170.71243931342, 19268.710969335472, 10703.357798532114, 10784.13726901846, 10815.351078935131, 11055.0603198729, 11073.726521450137, 8007.399445041528, 8132.105468398353, 8244.299234564889, 8332.33341332704, 8360.205780450848, 16559.337626494806, 16560.917195413946, 16626.334946602, 16681.74977375316, 16907.154642267593, 17179.0, 17196.226169822137, 17278.22247998818, 17655.558611034827, 17721.0, 11530.308181509135, 11835.112366572714, 11842.881571184178, 11863.703407414883, 11865.406729663655, 13080.542181023618, 13250.271235081778, 13412.32938353098, 13465.596032119052, 13549.525766598123, 10676.199285053497, 10845.295055821347, 10907.127429805756, 10943.28868623537, 11042.834357484804, 10714.196435267542, 10784.514696838818, 10889.292196007234, 10939.343639381663, 10981.17641176931, 9628.614855405705, 9634.13292803651, 9850.704478865646, 9851.507424628884, 9857.11285984265, 7209.963950180216, 7275.417966562556, 7370.557766534025, 7390.704371825067, 7408.888866666896, 7648.655810488653, 7727.536347818924, 7755.3020228177475, 7792.181820908723, 7884.526928384315], "value": 6433.550551002957, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.87353477793624, 479.95200479952007, 498.34716521357024, 500.81219542671494, 502.0636512903434, 6084.604500707456, 6175.598586091901, 6229.813105606828, 6270.341614130519, 6288.465480434157, 5711.400302968191, 6024.0, 6051.152838602599, 6064.969675151629, 6070.332263451028, 4819.228923372261, 4851.924383517844, 4867.634927380443, 4900.705957642535, 4948.703077815325, 4740.644451666121, 4867.2942423348595, 4870.65905386623, 4892.241702536095, 4938.901221975559, 4809.470958194614, 4838.927416088762, 4863.635227357945, 4870.441458733207, 4896.2410826321975, 4829.082474329866, 4831.178699621073, 4881.341018962466, 4906.0, 4917.385326834164, 4325.5025672047805, 4356.782160891975, 4358.629516491102, 4374.365716971053, 4427.225235583792, 5972.4027597240365, 6017.578769486137, 6029.276486821565, 6034.879302413971, 6040.275166979991, 5556.0, 5589.105743081128, 5600.803971860965, 5601.243832082658, 5618.15727640853, 6412.461009357768, 6428.517861160385, 6447.516436267299, 6450.322716114787, 6451.419372256472, 4701.811927522895, 4738.881527961794, 4746.833860814855, 4762.976185119087, 4771.594414474774, 6691.59850408977, 6718.361755633207, 6754.155730533684, 6773.559718618274, 6846.383825455733, 4834.419869615669, 4873.926891096637, 4875.341828853059, 4910.877228069256, 4944.728039957765, 4949.282354058641, 4950.579200767975, 4975.477574854661, 5003.624728145403, 5020.523050310215, 5598.804041858597, 5601.971990140023, 5623.381428042912, 5654.943450565442, 5687.203791469197, 6479.773207937699, 6528.51036172289, 6543.967280163569, 6584.209894812653, 6603.45309939653, 1616.5473667373155, 1624.293432356905, 1672.6403823178075, 1712.332190445727, 1729.161356741979, 1164.8078067118831, 1186.5194596188558, 1189.690680423079, 1194.3669854976877, 1199.7060720123625, 5700.458456446631, 5730.942690573124, 5737.454941780524, 5751.654900705971, 5757.078867381283, 6553.148090748172, 6660.733570657119, 6674.399304062657, 6703.916198548624, 6711.664416779142, 5692.658440493584, 5746.195532625436, 5763.221965034751, 5774.509166720875, 5814.389489103668, 3516.7889926604485, 3561.857525698943, 3572.1783989682517, 3585.2112535242727, 3587.8744243951337, 6419.646919419383, 6420.229572451242, 6451.903221451682, 6475.417212450902, 6513.902291465632, 6793.252742198453, 6829.214640316427, 6847.589144651337, 6888.242293347727, 6890.414314783199, 6404.238834320599, 6458.257300410419, 6458.644774537445, 6468.288488266287, 6513.316101809337, 6665.166854143232, 6667.533272670867, 6687.598744075371, 6731.293214212437, 6734.797956061326, 6564.179477565304, 6566.421300391394, 6569.540132190701, 6583.341665833378, 6625.768098116543, 6366.9044964326495, 6380.0, 6492.285848556749, 6503.512236582181, 6513.511486638616, 4914.8771280718265, 4940.75296235194, 4965.875853103701, 4972.204447288561, 4974.552290293891, 6429.2284925807335, 6585.473162146921, 6689.431398331197, 6785.423239024541, 6811.421029212573, 6300.0154951190225, 6366.713497892513, 6448.9032664511005, 6481.157449531531, 6483.98996311145, 6409.35906409374, 6428.0, 6452.451541619015, 6483.448906842783, 6502.772402965874, 6215.316315205414, 6342.175517182736, 6362.809115726535, 6366.586171898812, 6409.134766806422, 6384.489240860813, 6478.2550006749825, 6479.676016199265, 6548.934510654833, 6576.210854697276, 6289.245290565162, 6294.590851594815, 6331.176946996861, 6337.524685648503, 6342.7145778441], "value": 4978.794665553007, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [912.0605776050666, 913.3789023464044, 935.5696379665353, 946.6781294359917, 947.9336446448747, 18708.719369209455, 19608.117634706436, 19659.11533980971, 19667.704984425243, 19686.212551497923, 17493.650126997454, 17514.21186046628, 18422.447326580223, 22280.220192293273, 22291.21980730675, 1028.7325295423207, 1056.3556230699267, 1056.4136904018267, 1056.6882769582953, 1057.0961827637336, 1004.4826914139225, 1047.7275908263869, 1049.1344640671439, 1052.747340638245, 1056.27645063132, 1101.0420933787616, 1101.8402331661905, 1112.5327362507767, 1113.6158025481195, 1114.9275297105703, 1088.8911108889126, 1095.649392194498, 1099.5272033025797, 1100.0429626225155, 1109.1071687291726, 815.3803109636659, 815.955122468264, 817.3052905030677, 817.811903262253, 818.0388044048219, 17849.464516064538, 18552.16515256803, 20555.89722051395, 20830.27094051701, 20888.895555522126, 22073.889630551905, 22145.048619369692, 22497.0, 22497.55004899909, 22562.21032263879, 16876.493705188863, 16977.66044679112, 17016.40442584516, 17035.40376086831, 17189.140542972928, 3532.5231093802263, 3552.964470355315, 3556.78659280444, 3557.004038869121, 3557.1640664443835, 129458.0, 130606.0, 131140.0, 131306.0, 135105.324473377, 16796.748048779278, 17265.39571115005, 17656.911715441598, 17808.287668493373, 17840.0, 30850.0, 32358.83820580882, 32380.514292285636, 32451.837740811145, 32735.50896736551, 8457.365697572706, 8511.57442127891, 8640.654373825102, 8659.610317535726, 8738.30093592511, 3981.7810020448574, 4082.3672330788777, 4098.118904435561, 4106.691998100155, 4107.260693075275, 4987.077390682735, 4999.850004499871, 5107.080725469451, 5108.131617625013, 5114.0, 937.3672770879629, 942.966996155145, 960.2845879819579, 963.272729089533, 963.5952899782109, 656.6290046123925, 673.5487223560128, 682.2324884504932, 685.6503183376514, 691.7025678957996, 11271.38007409584, 11367.0, 11457.19799614019, 11694.94152529249, 12090.153689241668, 678.3284548297249, 686.3891136888174, 688.3460712323313, 692.9376356127973, 706.8939659051183, 5139.254808052811, 5165.457626949192, 5176.637635365563, 5192.792288308501, 5242.68543887368, 100437.0, 101477.0, 102173.0, 102507.4874625622, 103560.48219758853, 89770.0, 90385.0, 92217.0, 92265.53867230621, 93157.53421232986, 80400.59799701082, 81636.18363816285, 82428.58785706032, 83191.0, 84893.15106848853, 11806.586769463027, 12392.81410778821, 12659.493620255455, 12880.677983050498, 13122.540711075066, 35390.64609353874, 35488.0, 35736.821315894296, 36138.638613613526, 37940.241195176495, 64422.67788661027, 67297.0, 68024.3197568018, 69735.0, 70760.64619676869, 18598.81401186025, 19689.70465442991, 19713.802861971195, 19763.703544447133, 19953.800461995776, 18707.0, 18801.341953031577, 18830.717539237197, 20048.097835597142, 21009.57980840405, 18715.812841871953, 18799.90600046991, 18945.810541894403, 19461.318853840046, 19736.0, 19405.32081377145, 19644.705329419783, 19825.90087049613, 19899.303524376577, 20252.898735506227, 17689.203985820925, 17912.462626121236, 18232.0, 18334.449966501026, 18952.43142705721, 13025.739485210432, 13230.470781168646, 13418.127821691558, 13472.259025753678, 13761.518346857813, 14460.927695361455, 15135.016223945408, 15333.310001050202, 15544.06735595868, 15556.844431555539], "value": 9183.6502758546, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 108.0, 100.0, 106.0, 108.0, 109.0, 110.0, 99.0, 109.0, 111.0], "value": 106.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 123.0, 143.0, 182.0, 123.0, 148.0, 146.0, 120.0, 165.0, 119.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 164.0, 162.0, 163.0, 163.0, 166.0, 165.0, 167.0, 163.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 177.0, 74.0, 78.0, 74.0, 73.0, 156.0, 76.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 135.0, 78.0, 85.0, 79.0, 125.0, 79.0, 79.0, 122.0], "value": 94.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 115.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 150.0, 150.0, 149.0, 152.0, 150.0, 151.0, 151.0, 151.0, 150.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 98.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 54.0, 54.0, 54.0, 60.0, 56.0, 55.0, 54.0, 54.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 53.0, 56.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 78.0, 97.0, 86.0, 76.0, 76.0, 88.0, 79.0, 89.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 128.0, 123.0, 126.0, 131.0, 124.0, 124.0, 136.0, 127.0, 166.0], "value": 131.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 170.0, 177.0, 176.0, 180.0, 192.0, 176.0, 180.0, 180.0, 180.0], "value": 178.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 68.0, 65.0, 65.0, 67.0, 67.0, 67.0, 64.0], "value": 66.4, "unit": "score"}]}]} 13:04:05 INFO - Return code: 0 13:04:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:04:05 INFO - # TBPL SUCCESS # 13:04:05 INFO - Running post-action listener: _package_coverage_data 13:04:05 INFO - Running post-action listener: _resource_record_post_action 13:04:05 INFO - [mozharness: 2017-08-07 20:04:05.826267Z] Finished run-tests step (success) 13:04:05 INFO - Running post-run listener: _resource_record_post_run 13:04:05 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 5910528; Write bytes: 443707392; Read time: 2552; Write time: 320664 13:04:05 INFO - TinderboxPrint: CPU usage
13.1% 13:04:05 INFO - TinderboxPrint: I/O read bytes / time
5,910,528 / 2,552 13:04:05 INFO - TinderboxPrint: I/O write bytes / time
443,707,392 / 320,664 13:04:05 INFO - TinderboxPrint: CPU idle
8,268.4 (86.8%) 13:04:05 INFO - TinderboxPrint: CPU user
1,201.8 (12.6%) 13:04:05 INFO - TinderboxPrint: Swap in / out
0 / 0 13:04:05 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 117854208; Read time: 16; Write time: 91788 13:04:05 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:04:05 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 3690496; Write bytes: 246366208; Read time: 2044; Write time: 178496 13:04:06 INFO - Running post-run listener: _upload_blobber_files 13:04:06 INFO - Blob upload gear active. 13:04:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:04:06 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 13:04:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:04:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:04:06 INFO - (blobuploader) - INFO - Open directory for files ... 13:04:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:04:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:04:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:07 INFO - (blobuploader) - INFO - Done attempting. 13:04:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:04:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:04:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:08 INFO - (blobuploader) - INFO - Done attempting. 13:04:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:04:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:04:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:09 INFO - (blobuploader) - INFO - Done attempting. 13:04:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:04:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:04:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:04:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:04:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:04:17 INFO - (blobuploader) - INFO - Done attempting. 13:04:17 INFO - (blobuploader) - INFO - Iteration through files over. 13:04:17 INFO - Return code: 0 13:04:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:04:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:04:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3f1b188303275d3078a5f26fd0cb77a1aee49d89a417a3b80394831a31197933ddaad00a13664ca9b548c7b940a2fa9f99d65beab956a0468e47bb62d2ee9c44", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e3611e8059b69b85834b7c5877f56a018574a4c652706c8009dace0678b9f6c94592698eb1bfea1b6835eb305442bf65ef87b2c707d69de9335caa60674614a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/095895c30f1b02a959bf540b3749545640a7c74d8d493eadd6ae6139ef72c3a2c0505b207e3307caf7bcb4bea8ee78d3f8e928c9f4a441701df1eaf49b43ecd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb8b0c7b7f26f3cdf2cd7dbe466651a9c19902776ea5370619bcdcc9d2b94afa1f0d24577a9dd51b7a8dd9512c13d0e9cd79cbc3148654b45e770e7e0e0bd232"} 13:04:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:04:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:04:17 INFO - Contents: 13:04:17 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3f1b188303275d3078a5f26fd0cb77a1aee49d89a417a3b80394831a31197933ddaad00a13664ca9b548c7b940a2fa9f99d65beab956a0468e47bb62d2ee9c44", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e3611e8059b69b85834b7c5877f56a018574a4c652706c8009dace0678b9f6c94592698eb1bfea1b6835eb305442bf65ef87b2c707d69de9335caa60674614a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/095895c30f1b02a959bf540b3749545640a7c74d8d493eadd6ae6139ef72c3a2c0505b207e3307caf7bcb4bea8ee78d3f8e928c9f4a441701df1eaf49b43ecd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb8b0c7b7f26f3cdf2cd7dbe466651a9c19902776ea5370619bcdcc9d2b94afa1f0d24577a9dd51b7a8dd9512c13d0e9cd79cbc3148654b45e770e7e0e0bd232"} 13:04:17 INFO - Running post-run listener: copy_logs_to_upload_dir 13:04:17 INFO - Copying logs to upload dir... 13:04:17 INFO - mkdir: /builds/slave/test/build/upload/logs 13:04:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1258.428118 ========= master_lag: 0.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 58 secs) (at 2017-08-07 13:04:17.429932) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-07 13:04:17.433395) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505 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-Non-PGO/sha512/3f1b188303275d3078a5f26fd0cb77a1aee49d89a417a3b80394831a31197933ddaad00a13664ca9b548c7b940a2fa9f99d65beab956a0468e47bb62d2ee9c44", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e3611e8059b69b85834b7c5877f56a018574a4c652706c8009dace0678b9f6c94592698eb1bfea1b6835eb305442bf65ef87b2c707d69de9335caa60674614a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/095895c30f1b02a959bf540b3749545640a7c74d8d493eadd6ae6139ef72c3a2c0505b207e3307caf7bcb4bea8ee78d3f8e928c9f4a441701df1eaf49b43ecd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb8b0c7b7f26f3cdf2cd7dbe466651a9c19902776ea5370619bcdcc9d2b94afa1f0d24577a9dd51b7a8dd9512c13d0e9cd79cbc3148654b45e770e7e0e0bd232"} build_url:https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011131 build_url: 'https://queue.taskcluster.net/v1/task/eQ_3dHouQGmbtpzvbd4Udw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3f1b188303275d3078a5f26fd0cb77a1aee49d89a417a3b80394831a31197933ddaad00a13664ca9b548c7b940a2fa9f99d65beab956a0468e47bb62d2ee9c44", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e3611e8059b69b85834b7c5877f56a018574a4c652706c8009dace0678b9f6c94592698eb1bfea1b6835eb305442bf65ef87b2c707d69de9335caa60674614a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/095895c30f1b02a959bf540b3749545640a7c74d8d493eadd6ae6139ef72c3a2c0505b207e3307caf7bcb4bea8ee78d3f8e928c9f4a441701df1eaf49b43ecd4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb8b0c7b7f26f3cdf2cd7dbe466651a9c19902776ea5370619bcdcc9d2b94afa1f0d24577a9dd51b7a8dd9512c13d0e9cd79cbc3148654b45e770e7e0e0bd232"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-07 13:04:17.458008) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 13:04:17.458355) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1502134498.211237-74020505 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 13:04:17.508040) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-07 13:04:17.508346) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-07 13:04:17.508672) ========= ========= Total master_lag: 0.30 =========