builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-010 starttime: 1502107535.97 results: success (0) revision: 47248637eafa9a38dade8dc3aa6c4736177c8d8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:35.969540) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:35.970237) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:35.970613) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:35.990811) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:35.991355) ========= 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=eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-07 05:05:36-- 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% 93.7M=0s 2017-08-07 05:05:36 (93.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135375 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:36.149246) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:36.149846) ========= 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=eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016136 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:36.207480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-07 05:05:36.207957) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 47248637eafa9a38dade8dc3aa6c4736177c8d8d --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 47248637eafa9a38dade8dc3aa6c4736177c8d8d --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=eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-07 05:05:36,604 truncating revision to first 12 chars 2017-08-07 05:05:36,604 Setting DEBUG logging. 2017-08-07 05:05:36,604 attempt 1/10 2017-08-07 05:05:36,604 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/47248637eafa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-07 05:05:38,163 unpacking tar archive at: mozilla-central-47248637eafa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.867837 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-07 05:05:38.095511) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:38.095972) ========= script_repo_revision: 47248637eafa9a38dade8dc3aa6c4736177c8d8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:38.096537) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:38.096961) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-07 05:05:38.110724) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-07 05:05:38.111164) ========= /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=eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:05:38 INFO - MultiFileLogger online at 20170807 05:05:38 in /builds/slave/test 05:05:38 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 05:05:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:38 INFO - {'append_to_log': False, 05:05:38 INFO - 'base_work_dir': '/builds/slave/test', 05:05:38 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:05:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:38 INFO - 'branch': 'Firefox-Non-PGO', 05:05:38 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:38 INFO - 'code_coverage': False, 05:05:38 INFO - 'config_files': ('talos/linux_config.py',), 05:05:38 INFO - 'default_actions': ('clobber', 05:05:38 INFO - 'read-buildbot-config', 05:05:38 INFO - 'download-and-extract', 05:05:38 INFO - 'populate-webroot', 05:05:38 INFO - 'create-virtualenv', 05:05:38 INFO - 'install', 05:05:38 INFO - 'setup-mitmproxy', 05:05:38 INFO - 'run-tests'), 05:05:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:38 INFO - 'disable_ccov_upload': False, 05:05:38 INFO - 'download_minidump_stackwalk': True, 05:05:38 INFO - 'download_symbols': 'ondemand', 05:05:38 INFO - 'enable_stylo': False, 05:05:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:38 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:38 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:38 INFO - 'gecko_profile': False, 05:05:38 INFO - 'gecko_profile_interval': 0, 05:05:38 INFO - 'installer_path': 'installer.exe', 05:05:38 INFO - 'log_level': 'info', 05:05:38 INFO - 'log_name': 'talos', 05:05:38 INFO - 'log_to_console': True, 05:05:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:05:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:05:38 INFO - 'opt_config_files': (), 05:05:38 INFO - 'pip_index': False, 05:05:38 INFO - 'suite': 'dromaeojs-e10s', 05:05:38 INFO - 'system_bits': '32', 05:05:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:38 INFO - 'title': 'talos-linux64-ix-010', 05:05:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:38 INFO - 'use_talos_json': True, 05:05:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:38 INFO - 'work_dir': 'build'} 05:05:38 INFO - [mozharness: 2017-08-07 12:05:38.614326Z] Running clobber step. 05:05:38 INFO - Running pre-action listener: _resource_record_pre_action 05:05:38 INFO - Running main action method: clobber 05:05:38 INFO - rmtree: /builds/slave/test/build 05:05:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:40 INFO - Running post-action listener: _resource_record_post_action 05:05:40 INFO - [mozharness: 2017-08-07 12:05:40.183250Z] Finished clobber step (success) 05:05:40 INFO - [mozharness: 2017-08-07 12:05:40.183351Z] Running read-buildbot-config step. 05:05:40 INFO - Running pre-action listener: _resource_record_pre_action 05:05:40 INFO - Running main action method: read_buildbot_config 05:05:40 INFO - Using buildbot properties: 05:05:40 INFO - { 05:05:40 INFO - "project": "", 05:05:40 INFO - "product": "firefox", 05:05:40 INFO - "who": "cbook@mozilla.com", 05:05:40 INFO - "installer_path": "public/build/target.tar.bz2", 05:05:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:05:40 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:05:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:05:40 INFO - "stage_platform": "linux64", 05:05:40 INFO - "basedir": "/builds/slave/test", 05:05:40 INFO - "buildnumber": 760, 05:05:40 INFO - "platform": "ubuntu64_hw", 05:05:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:05:40 INFO - "slavebuilddir": "test", 05:05:40 INFO - "taskId": "IERxR6SgT5mB4nVOtsBPew", 05:05:40 INFO - "branch": "mozilla-central", 05:05:40 INFO - "script_repo_revision": "production", 05:05:40 INFO - "revision": "47248637eafa9a38dade8dc3aa6c4736177c8d8d", 05:05:40 INFO - "slavename": "talos-linux64-ix-010", 05:05:40 INFO - "repo_path": "mozilla-central" 05:05:40 INFO - } 05:05:40 INFO - Finding installer, test and symbols from parent task. 05:05:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IERxR6SgT5mB4nVOtsBPew'}, attempt #1 05:05:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IERxR6SgT5mB4nVOtsBPew'}, attempt #1 05:05:41 INFO - Task dependencies: FK6Vw9RDTvi4Ea5oCnVSGw 05:05:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw'}, attempt #1 05:05:42 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.tar.bz2 05:05:42 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.test_packages.json 05:05:42 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.crashreporter-symbols.zip 05:05:42 INFO - Running post-action listener: _resource_record_post_action 05:05:42 INFO - [mozharness: 2017-08-07 12:05:42.036214Z] Finished read-buildbot-config step (success) 05:05:42 INFO - [mozharness: 2017-08-07 12:05:42.036434Z] Running download-and-extract step. 05:05:42 INFO - Running pre-action listener: _resource_record_pre_action 05:05:42 INFO - Running main action method: download_and_extract 05:05:42 INFO - mkdir: /builds/slave/test/build/tests 05:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:42 INFO - trying https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.test_packages.json 05:05:42 INFO - Downloading https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:05:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:05:42 INFO - Downloaded 989 bytes. 05:05:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:05:42 INFO - Using the following test package requirements: 05:05:42 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:05:42 INFO - u'common': [u'target.common.tests.zip'], 05:05:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:05:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:05:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:05:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:05:42 INFO - u'mozbase': [u'target.common.tests.zip'], 05:05:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:05:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:05:42 INFO - u'web-platform': [u'target.common.tests.zip', 05:05:42 INFO - u'target.web-platform.tests.tar.gz'], 05:05:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:05:42 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:05:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.common.tests.zip 05:05:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:05:42 INFO - Fetch https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.common.tests.zip into memory 05:05:43 INFO - Content-Length response header: 54284838 05:05:43 INFO - Bytes received: 54284838 05:05:54 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:05:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.common.tests.zip 05:05:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:05:54 INFO - Fetch https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.common.tests.zip into memory 05:05:55 INFO - Content-Length response header: 54284838 05:05:55 INFO - Bytes received: 54284838 05:06:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.talos.tests.zip 05:06:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:06:07 INFO - Fetch https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.talos.tests.zip into memory 05:06:08 INFO - Content-Length response header: 14496008 05:06:08 INFO - Bytes received: 14496008 05:06: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')]} 05:06:08 INFO - trying https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.tar.bz2 05:06:08 INFO - Downloading https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:06:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:06:10 INFO - Downloaded 61707857 bytes. 05:06:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.tar.bz2 05:06:10 INFO - mkdir: /builds/slave/test/properties 05:06:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:06:10 INFO - Writing to file /builds/slave/test/properties/build_url 05:06:10 INFO - Contents: 05:06:10 INFO - build_url:https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.tar.bz2 05:06:10 INFO - Running post-action listener: _resource_record_post_action 05:06:10 INFO - Running post-action listener: set_extra_try_arguments 05:06:10 INFO - [mozharness: 2017-08-07 12:06:10.284253Z] Finished download-and-extract step (success) 05:06:10 INFO - [mozharness: 2017-08-07 12:06:10.284352Z] Running populate-webroot step. 05:06:10 INFO - Running pre-action listener: _resource_record_pre_action 05:06:10 INFO - Running main action method: populate_webroot 05:06:10 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:06:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:06:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:06:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:06:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:06:10 INFO - 'tests': ['damp', 'tps']}, 05:06:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:06:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:06:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:06:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:06:10 INFO - 'other-e10s': {'tests': ['a11yr', 05:06:10 INFO - 'ts_paint', 05:06:10 INFO - 'tpaint', 05:06:10 INFO - 'sessionrestore', 05:06:10 INFO - 'sessionrestore_many_windows', 05:06:10 INFO - 'sessionrestore_no_auto_restore', 05:06:10 INFO - 'tabpaint']}, 05:06:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:06:10 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:06:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:06:10 INFO - 'tsvgr_opacity', 05:06:10 INFO - 'tart', 05:06:10 INFO - 'tscrollx', 05:06:10 INFO - 'tsvg_static']}, 05:06:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:06:10 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:06:10 INFO - 'talos_options': ['--mitmproxy', 05:06:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:06:10 INFO - '--firstNonBlankPaint'], 05:06:10 INFO - 'tests': ['tp6_google', 05:06:10 INFO - 'tp6_youtube', 05:06:10 INFO - 'tp6_amazon', 05:06:10 INFO - 'tp6_facebook']}, 05:06:10 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:06:10 INFO - 'talos_options': ['--stylo', 05:06:10 INFO - '--mitmproxy', 05:06:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:06:10 INFO - '--firstNonBlankPaint'], 05:06:10 INFO - 'tests': ['tp6_google', 05:06:10 INFO - 'tp6_youtube', 05:06:10 INFO - 'tp6_amazon', 05:06:10 INFO - 'tp6_facebook']}, 05:06:10 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:06:10 INFO - 'talos_options': ['--stylo', 05:06:10 INFO - '--stylo-threads=1', 05:06:10 INFO - '--mitmproxy', 05:06:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:06:10 INFO - '--firstNonBlankPaint'], 05:06:10 INFO - 'tests': ['tp6_google', 05:06:10 INFO - 'tp6_youtube', 05:06:10 INFO - 'tp6_amazon', 05:06:10 INFO - 'tp6_facebook']}, 05:06:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:06:10 INFO - 'talos_options': ['--xperf_path', 05:06:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:06:10 INFO - 'tests': ['tp5n']}}} 05:06:10 INFO - Running post-action listener: _resource_record_post_action 05:06:10 INFO - [mozharness: 2017-08-07 12:06:10.292730Z] Finished populate-webroot step (success) 05:06:10 INFO - [mozharness: 2017-08-07 12:06:10.292819Z] Running create-virtualenv step. 05:06:10 INFO - Running pre-action listener: _resource_record_pre_action 05:06:10 INFO - Running main action method: create_virtualenv 05:06:10 INFO - Creating virtualenv /builds/slave/test/build/venv 05:06:10 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 05:06:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:06:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:06:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:06:10 INFO - Using real prefix '/usr' 05:06:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:06:10 INFO - Installing distribute.............................................................................................................................................................................................done. 05:06:12 INFO - Installing pip.................done. 05:06:12 INFO - Return code: 0 05:06:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:06:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:06:13 INFO - Reading from file tmpfile_stdout 05:06:13 INFO - Output received: 05:06:13 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) 05:06:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:13 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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:13 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 05:06:13 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 05:06:13 INFO - Using env: {'DISPLAY': ':0', 05:06:13 INFO - 'HOME': '/home/cltbld', 05:06:13 INFO - 'LANG': 'en_US.UTF-8', 05:06:13 INFO - 'LANGUAGE': 'en_US:en', 05:06:13 INFO - 'LOGNAME': 'cltbld', 05:06:13 INFO - 'MAIL': '/var/mail/cltbld', 05:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:13 INFO - 'MOZ_NO_REMOTE': '1', 05:06:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:13 INFO - 'NO_EM_RESTART': '1', 05:06:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:13 INFO - 'PWD': '/builds/slave/test', 05:06:13 INFO - 'SHELL': '/bin/bash', 05:06:13 INFO - 'SHLVL': '1', 05:06:13 INFO - 'TERM': 'linux', 05:06:13 INFO - 'TMOUT': '86400', 05:06:13 INFO - 'USER': 'cltbld', 05:06:13 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:13 INFO - '_': '/tools/buildbot/bin/python'} 05:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:13 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 05:06:13 INFO - Cleaning up... 05:06:13 INFO - Return code: 0 05:06:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:13 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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:13 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 05:06:13 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 05:06:13 INFO - Using env: {'DISPLAY': ':0', 05:06:13 INFO - 'HOME': '/home/cltbld', 05:06:13 INFO - 'LANG': 'en_US.UTF-8', 05:06:13 INFO - 'LANGUAGE': 'en_US:en', 05:06:13 INFO - 'LOGNAME': 'cltbld', 05:06:13 INFO - 'MAIL': '/var/mail/cltbld', 05:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:13 INFO - 'MOZ_NO_REMOTE': '1', 05:06:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:13 INFO - 'NO_EM_RESTART': '1', 05:06:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:13 INFO - 'PWD': '/builds/slave/test', 05:06:13 INFO - 'SHELL': '/bin/bash', 05:06:13 INFO - 'SHLVL': '1', 05:06:13 INFO - 'TERM': 'linux', 05:06:13 INFO - 'TMOUT': '86400', 05:06:13 INFO - 'USER': 'cltbld', 05:06:13 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:13 INFO - '_': '/tools/buildbot/bin/python'} 05:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:13 INFO - Downloading/unpacking psutil>=3.1.1 05:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:06:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:14 INFO - Installing collected packages: psutil 05:06:14 INFO - Running setup.py install for psutil 05:06:14 INFO - building 'psutil._psutil_linux' extension 05:06:14 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 05:06:14 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 05:06:14 INFO - building 'psutil._psutil_posix' extension 05:06:14 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 05:06:15 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 05:06:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:06:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:06:15 INFO - Successfully installed psutil 05:06:15 INFO - Cleaning up... 05:06:15 INFO - Return code: 0 05:06:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:15 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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:15 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 05:06:15 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 05:06:15 INFO - Using env: {'DISPLAY': ':0', 05:06:15 INFO - 'HOME': '/home/cltbld', 05:06:15 INFO - 'LANG': 'en_US.UTF-8', 05:06:15 INFO - 'LANGUAGE': 'en_US:en', 05:06:15 INFO - 'LOGNAME': 'cltbld', 05:06:15 INFO - 'MAIL': '/var/mail/cltbld', 05:06:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:15 INFO - 'MOZ_NO_REMOTE': '1', 05:06:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:15 INFO - 'NO_EM_RESTART': '1', 05:06:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:15 INFO - 'PWD': '/builds/slave/test', 05:06:15 INFO - 'SHELL': '/bin/bash', 05:06:15 INFO - 'SHLVL': '1', 05:06:15 INFO - 'TERM': 'linux', 05:06:15 INFO - 'TMOUT': '86400', 05:06:15 INFO - 'USER': 'cltbld', 05:06:15 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:15 INFO - '_': '/tools/buildbot/bin/python'} 05:06:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:06:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:06:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:06:16 INFO - Installing collected packages: mozsystemmonitor 05:06:16 INFO - Running setup.py install for mozsystemmonitor 05:06:16 INFO - Successfully installed mozsystemmonitor 05:06:16 INFO - Cleaning up... 05:06:16 INFO - Return code: 0 05:06:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:06:16 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')]} 05:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:16 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')]} 05:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:16 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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:16 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 05:06:16 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 05:06:16 INFO - Using env: {'DISPLAY': ':0', 05:06:16 INFO - 'HOME': '/home/cltbld', 05:06:16 INFO - 'LANG': 'en_US.UTF-8', 05:06:16 INFO - 'LANGUAGE': 'en_US:en', 05:06:16 INFO - 'LOGNAME': 'cltbld', 05:06:16 INFO - 'MAIL': '/var/mail/cltbld', 05:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:16 INFO - 'MOZ_NO_REMOTE': '1', 05:06:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:16 INFO - 'NO_EM_RESTART': '1', 05:06:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:16 INFO - 'PWD': '/builds/slave/test', 05:06:16 INFO - 'SHELL': '/bin/bash', 05:06:16 INFO - 'SHLVL': '1', 05:06:16 INFO - 'TERM': 'linux', 05:06:16 INFO - 'TMOUT': '86400', 05:06:16 INFO - 'USER': 'cltbld', 05:06:16 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:16 INFO - '_': '/tools/buildbot/bin/python'} 05:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:16 INFO - Downloading/unpacking jsonschema==2.5.1 05:06:16 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 05:06:16 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 05:06:16 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 05:06:16 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 05:06:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:06:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:06:18 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 05:06:18 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 05:06:18 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 05:06:18 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 05:06:18 INFO - Downloading functools32-3.2.3-2.tar.gz 05:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:06:18 INFO - warning: no files found matching '*.txt' 05:06:18 INFO - no previously-included directories found matching 'build' 05:06:18 INFO - no previously-included directories found matching 'dist' 05:06:18 INFO - no previously-included directories found matching '.git*' 05:06:18 INFO - Installing collected packages: jsonschema, functools32 05:06:18 INFO - Running setup.py install for functools32 05:06:18 INFO - warning: no files found matching '*.txt' 05:06:18 INFO - no previously-included directories found matching 'build' 05:06:18 INFO - no previously-included directories found matching 'dist' 05:06:18 INFO - no previously-included directories found matching '.git*' 05:06:18 INFO - Successfully installed jsonschema functools32 05:06:18 INFO - Cleaning up... 05:06:18 INFO - Return code: 0 05:06:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:06:18 INFO - Using env: {'DISPLAY': ':0', 05:06:18 INFO - 'HOME': '/home/cltbld', 05:06:18 INFO - 'LANG': 'en_US.UTF-8', 05:06:18 INFO - 'LANGUAGE': 'en_US:en', 05:06:18 INFO - 'LOGNAME': 'cltbld', 05:06:18 INFO - 'MAIL': '/var/mail/cltbld', 05:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:18 INFO - 'MOZ_NO_REMOTE': '1', 05:06:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:18 INFO - 'NO_EM_RESTART': '1', 05:06:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:18 INFO - 'PWD': '/builds/slave/test', 05:06:18 INFO - 'SHELL': '/bin/bash', 05:06:18 INFO - 'SHLVL': '1', 05:06:18 INFO - 'TERM': 'linux', 05:06:18 INFO - 'TMOUT': '86400', 05:06:18 INFO - 'USER': 'cltbld', 05:06:18 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:18 INFO - '_': '/tools/buildbot/bin/python'} 05:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:06:18 INFO - Cleaning up... 05:06:18 INFO - Return code: 0 05:06:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:06:18 INFO - Using env: {'DISPLAY': ':0', 05:06:18 INFO - 'HOME': '/home/cltbld', 05:06:18 INFO - 'LANG': 'en_US.UTF-8', 05:06:18 INFO - 'LANGUAGE': 'en_US:en', 05:06:18 INFO - 'LOGNAME': 'cltbld', 05:06:18 INFO - 'MAIL': '/var/mail/cltbld', 05:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:18 INFO - 'MOZ_NO_REMOTE': '1', 05:06:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:18 INFO - 'NO_EM_RESTART': '1', 05:06:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:18 INFO - 'PWD': '/builds/slave/test', 05:06:18 INFO - 'SHELL': '/bin/bash', 05:06:18 INFO - 'SHLVL': '1', 05:06:18 INFO - 'TERM': 'linux', 05:06:18 INFO - 'TMOUT': '86400', 05:06:18 INFO - 'USER': 'cltbld', 05:06:18 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:18 INFO - '_': '/tools/buildbot/bin/python'} 05:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:06:18 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 05:06:18 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 05:06:18 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 05:06:18 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 05:06:19 INFO - Downloading blobuploader-1.2.4.tar.gz 05:06:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:06:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:06:20 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 05:06:20 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 05:06:20 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 05:06:20 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 05:06:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:06:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:06:20 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 05:06:20 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 05:06:20 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 05:06:20 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 05:06:20 INFO - Downloading docopt-0.6.1.tar.gz 05:06:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:06:20 INFO - Installing collected packages: blobuploader, requests, docopt 05:06:20 INFO - Running setup.py install for blobuploader 05:06:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:06:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:06:20 INFO - Running setup.py install for requests 05:06:21 INFO - Running setup.py install for docopt 05:06:21 INFO - Successfully installed blobuploader requests docopt 05:06:21 INFO - Cleaning up... 05:06:21 INFO - Return code: 0 05:06:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06: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')]} 05:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06: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')]} 05:06:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:21 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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:21 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 05:06:21 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 05:06:21 INFO - Using env: {'DISPLAY': ':0', 05:06:21 INFO - 'HOME': '/home/cltbld', 05:06:21 INFO - 'LANG': 'en_US.UTF-8', 05:06:21 INFO - 'LANGUAGE': 'en_US:en', 05:06:21 INFO - 'LOGNAME': 'cltbld', 05:06:21 INFO - 'MAIL': '/var/mail/cltbld', 05:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:21 INFO - 'MOZ_NO_REMOTE': '1', 05:06:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:21 INFO - 'NO_EM_RESTART': '1', 05:06:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:21 INFO - 'PWD': '/builds/slave/test', 05:06:21 INFO - 'SHELL': '/bin/bash', 05:06:21 INFO - 'SHLVL': '1', 05:06:21 INFO - 'TERM': 'linux', 05:06:21 INFO - 'TMOUT': '86400', 05:06:21 INFO - 'USER': 'cltbld', 05:06:21 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:21 INFO - '_': '/tools/buildbot/bin/python'} 05:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:21 INFO - Running setup.py (path:/tmp/pip-2dH4ZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:21 INFO - Running setup.py (path:/tmp/pip-my_uIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:21 INFO - Running setup.py (path:/tmp/pip-GFPhy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:21 INFO - Running setup.py (path:/tmp/pip-1cZJUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:21 INFO - Running setup.py (path:/tmp/pip-YRJueD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:21 INFO - Running setup.py (path:/tmp/pip-S8FDY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:21 INFO - Running setup.py (path:/tmp/pip-FgetGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:21 INFO - Running setup.py (path:/tmp/pip-C1PKD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:21 INFO - Running setup.py (path:/tmp/pip-MVbS9g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:22 INFO - Running setup.py (path:/tmp/pip-O59TwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:22 INFO - Running setup.py (path:/tmp/pip-xtrdcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:22 INFO - Running setup.py (path:/tmp/pip-th83oK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:22 INFO - Running setup.py (path:/tmp/pip-o3BlTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:22 INFO - Running setup.py (path:/tmp/pip-Yj_V9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:22 INFO - Running setup.py (path:/tmp/pip-A6WMP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:22 INFO - Running setup.py (path:/tmp/pip-Vv6UYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:22 INFO - Running setup.py (path:/tmp/pip-MHExaL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:06:22 INFO - Running setup.py install for manifestparser 05:06:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:06:22 INFO - Running setup.py install for mozcrash 05:06:22 INFO - Running setup.py install for mozdebug 05:06:22 INFO - Running setup.py install for mozdevice 05:06:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Running setup.py install for mozfile 05:06:23 INFO - Running setup.py install for mozhttpd 05:06:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Running setup.py install for mozinfo 05:06:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Running setup.py install for mozInstall 05:06:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Running setup.py install for mozleak 05:06:23 INFO - Running setup.py install for mozlog 05:06:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Running setup.py install for moznetwork 05:06:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Running setup.py install for mozprocess 05:06:23 INFO - Running setup.py install for mozprofile 05:06:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:06:23 INFO - Running setup.py install for mozrunner 05:06:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:06:24 INFO - Running setup.py install for mozscreenshot 05:06:24 INFO - Running setup.py install for moztest 05:06:24 INFO - Running setup.py install for mozversion 05:06:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:06:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:06:24 INFO - Cleaning up... 05:06:24 INFO - Return code: 0 05:06:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:24 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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:24 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 05:06:24 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 05:06:24 INFO - Using env: {'DISPLAY': ':0', 05:06:24 INFO - 'HOME': '/home/cltbld', 05:06:24 INFO - 'LANG': 'en_US.UTF-8', 05:06:24 INFO - 'LANGUAGE': 'en_US:en', 05:06:24 INFO - 'LOGNAME': 'cltbld', 05:06:24 INFO - 'MAIL': '/var/mail/cltbld', 05:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:24 INFO - 'MOZ_NO_REMOTE': '1', 05:06:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:24 INFO - 'NO_EM_RESTART': '1', 05:06:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:24 INFO - 'PWD': '/builds/slave/test', 05:06:24 INFO - 'SHELL': '/bin/bash', 05:06:24 INFO - 'SHLVL': '1', 05:06:24 INFO - 'TERM': 'linux', 05:06:24 INFO - 'TMOUT': '86400', 05:06:24 INFO - 'USER': 'cltbld', 05:06:24 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:24 INFO - '_': '/tools/buildbot/bin/python'} 05:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:06:24 INFO - Running setup.py (path:/tmp/pip-8e7_nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:06:24 INFO - Running setup.py (path:/tmp/pip-UfrKdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:06:24 INFO - Running setup.py (path:/tmp/pip-uKSj1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:06:24 INFO - Running setup.py (path:/tmp/pip-A_wM95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:06:24 INFO - Running setup.py (path:/tmp/pip-eDfeiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:06:24 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)) 05:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:06:24 INFO - Running setup.py (path:/tmp/pip-BDNfRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:06:25 INFO - Running setup.py (path:/tmp/pip-gKERPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:25 INFO - Running setup.py (path:/tmp/pip-p6xcJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:25 INFO - Running setup.py (path:/tmp/pip-asTSsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:25 INFO - Running setup.py (path:/tmp/pip-tEQMr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:25 INFO - Running setup.py (path:/tmp/pip-r1UmGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:25 INFO - Running setup.py (path:/tmp/pip-AWZg84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:25 INFO - Running setup.py (path:/tmp/pip-nz_FMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:25 INFO - Running setup.py (path:/tmp/pip-B1aI4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:25 INFO - Running setup.py (path:/tmp/pip-G1DOla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:25 INFO - Running setup.py (path:/tmp/pip-yQMV5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:25 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)) 05:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:25 INFO - Running setup.py (path:/tmp/pip-gk0qjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:25 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)) 05:06:25 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)) 05:06:25 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)) 05:06:25 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)) 05:06:25 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)) 05:06:25 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)) 05:06:25 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)) 05:06:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:06:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:06:26 INFO - Downloading blessings-1.6.tar.gz 05:06:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:27 INFO - Installing collected packages: blessings 05:06:27 INFO - Running setup.py install for blessings 05:06:27 INFO - Successfully installed blessings 05:06:27 INFO - Cleaning up... 05:06:27 INFO - Return code: 0 05:06:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:27 INFO - Reading from file tmpfile_stdout 05:06:27 INFO - Current package versions: 05:06:27 INFO - argparse == 1.2.1 05:06:27 INFO - blessings == 1.6 05:06:27 INFO - blobuploader == 1.2.4 05:06:27 INFO - docopt == 0.6.1 05:06:27 INFO - functools32 == 3.2.3-2 05:06:27 INFO - jsonschema == 2.5.1 05:06:27 INFO - manifestparser == 1.1 05:06:27 INFO - mozInstall == 1.13 05:06:27 INFO - mozcrash == 1.0 05:06:27 INFO - mozdebug == 0.1 05:06:27 INFO - mozdevice == 0.51 05:06:27 INFO - mozfile == 1.2 05:06:27 INFO - mozhttpd == 0.7 05:06:27 INFO - mozinfo == 0.10 05:06:27 INFO - mozleak == 0.1 05:06:27 INFO - mozlog == 3.5 05:06:27 INFO - moznetwork == 0.27 05:06:27 INFO - mozprocess == 0.25 05:06:27 INFO - mozprofile == 0.28 05:06:27 INFO - mozrunner == 6.13 05:06:27 INFO - mozscreenshot == 0.1 05:06:27 INFO - mozsystemmonitor == 0.3 05:06:27 INFO - moztest == 0.8 05:06:27 INFO - mozversion == 1.4 05:06:27 INFO - psutil == 3.1.1 05:06:27 INFO - requests == 1.2.3 05:06:27 INFO - wsgiref == 0.1.2 05:06:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:06:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:27 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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:27 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 05:06:27 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 05:06:27 INFO - Using env: {'DISPLAY': ':0', 05:06:27 INFO - 'HOME': '/home/cltbld', 05:06:27 INFO - 'LANG': 'en_US.UTF-8', 05:06:27 INFO - 'LANGUAGE': 'en_US:en', 05:06:27 INFO - 'LOGNAME': 'cltbld', 05:06:27 INFO - 'MAIL': '/var/mail/cltbld', 05:06:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:27 INFO - 'MOZ_NO_REMOTE': '1', 05:06:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:27 INFO - 'NO_EM_RESTART': '1', 05:06:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:27 INFO - 'PWD': '/builds/slave/test', 05:06:27 INFO - 'SHELL': '/bin/bash', 05:06:27 INFO - 'SHLVL': '1', 05:06:27 INFO - 'TERM': 'linux', 05:06:27 INFO - 'TMOUT': '86400', 05:06:27 INFO - 'USER': 'cltbld', 05:06:27 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:27 INFO - '_': '/tools/buildbot/bin/python'} 05:06:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:27 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)) 05:06:27 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)) 05:06:27 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)) 05:06:27 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)) 05:06:27 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)) 05:06:27 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)) 05:06:27 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)) 05:06:27 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)) 05:06:27 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)) 05:06:27 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:06:27 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 05:06:27 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 05:06:27 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 05:06:27 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 05:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:06:28 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)) 05:06:28 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)) 05:06:28 INFO - Installing collected packages: simplejson 05:06:28 INFO - Running setup.py install for simplejson 05:06:28 INFO - building 'simplejson._speedups' extension 05:06:28 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 05:06:29 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 05:06:29 INFO - Successfully installed simplejson 05:06:29 INFO - Cleaning up... 05:06:29 INFO - Return code: 0 05:06:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06: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')]} 05:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06: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')]} 05:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:29 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 0x24491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2616a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b9d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, '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': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', '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 05:06:29 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 05:06:29 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 05:06:29 INFO - Using env: {'DISPLAY': ':0', 05:06:29 INFO - 'HOME': '/home/cltbld', 05:06:29 INFO - 'LANG': 'en_US.UTF-8', 05:06:29 INFO - 'LANGUAGE': 'en_US:en', 05:06:29 INFO - 'LOGNAME': 'cltbld', 05:06:29 INFO - 'MAIL': '/var/mail/cltbld', 05:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:29 INFO - 'MOZ_NO_REMOTE': '1', 05:06:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:29 INFO - 'NO_EM_RESTART': '1', 05:06:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:29 INFO - 'PWD': '/builds/slave/test', 05:06:29 INFO - 'SHELL': '/bin/bash', 05:06:29 INFO - 'SHLVL': '1', 05:06:29 INFO - 'TERM': 'linux', 05:06:29 INFO - 'TMOUT': '86400', 05:06:29 INFO - 'USER': 'cltbld', 05:06:29 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:29 INFO - '_': '/tools/buildbot/bin/python'} 05:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:06:30 INFO - Cleaning up... 05:06:30 INFO - Return code: 0 05:06:30 INFO - Running post-action listener: _resource_record_post_action 05:06:30 INFO - Running post-action listener: _start_resource_monitoring 05:06:30 INFO - Starting resource monitoring. 05:06:30 INFO - [mozharness: 2017-08-07 12:06:30.091523Z] Finished create-virtualenv step (success) 05:06:30 INFO - [mozharness: 2017-08-07 12:06:30.092036Z] Running install step. 05:06:30 INFO - Running pre-action listener: _resource_record_pre_action 05:06:30 INFO - Running main action method: install 05:06:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:30 INFO - Reading from file tmpfile_stdout 05:06:30 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:30 INFO - Reading from file tmpfile_stdout 05:06:30 INFO - Output received: 05:06:30 INFO - Usage: mozinstall [options] installer 05:06:30 INFO - Options: 05:06:30 INFO - -h, --help show this help message and exit 05:06:30 INFO - -d DEST, --destination=DEST 05:06:30 INFO - Directory to install application into. [default: 05:06:30 INFO - "/builds/slave/test"] 05:06:30 INFO - --app=APP Application being installed. [default: firefox] 05:06:30 INFO - mkdir: /builds/slave/test/build/application 05:06:30 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'] 05:06:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:06:48 INFO - Reading from file tmpfile_stdout 05:06:48 INFO - Output received: 05:06:48 INFO - /builds/slave/test/build/application/firefox/firefox 05:06:48 INFO - Running post-action listener: _resource_record_post_action 05:06:48 INFO - [mozharness: 2017-08-07 12:06:48.399410Z] Finished install step (success) 05:06:48 INFO - [mozharness: 2017-08-07 12:06:48.399692Z] Running setup-mitmproxy step. 05:06:48 INFO - Running pre-action listener: _resource_record_pre_action 05:06:48 INFO - Running main action method: setup_mitmproxy 05:06:48 INFO - Skipping: mitmproxy is not required 05:06:48 INFO - Running post-action listener: _resource_record_post_action 05:06:48 INFO - [mozharness: 2017-08-07 12:06:48.400835Z] Finished setup-mitmproxy step (success) 05:06:48 INFO - [mozharness: 2017-08-07 12:06:48.401106Z] Running run-tests step. 05:06:48 INFO - Running pre-action listener: _resource_record_pre_action 05:06:48 INFO - Running pre-action listener: _set_gcov_prefix 05:06:48 INFO - Running main action method: run_tests 05:06:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:48 INFO - Python 2.7.3 05:06:48 INFO - Return code: 0 05:06:48 INFO - grabbing minidump binary from tooltool 05:06:48 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')]} 05:06:48 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 0x27b6eb0>, '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 0x27b7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27b8240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:48 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 05:06:48 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 05:06:48 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 05:06:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:48 INFO - Return code: 0 05:06:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:06:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:48 INFO - ENV: RUST_BACKTRACE is now 1 05:06:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:06:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:48 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/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 05:06:48 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/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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 05:06:48 INFO - Using env: {'DISPLAY': ':0', 05:06:48 INFO - 'HOME': '/home/cltbld', 05:06:48 INFO - 'LANG': 'en_US.UTF-8', 05:06:48 INFO - 'LANGUAGE': 'en_US:en', 05:06:48 INFO - 'LOGNAME': 'cltbld', 05:06:48 INFO - 'MAIL': '/var/mail/cltbld', 05:06:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:48 INFO - 'MOZ_NO_REMOTE': '1', 05:06:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:06:48 INFO - 'NO_EM_RESTART': '1', 05:06:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:48 INFO - 'PWD': '/builds/slave/test', 05:06:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:48 INFO - 'RUST_BACKTRACE': '1', 05:06:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:06:48 INFO - 'SHELL': '/bin/bash', 05:06:48 INFO - 'SHLVL': '1', 05:06:48 INFO - 'TERM': 'linux', 05:06:48 INFO - 'TMOUT': '86400', 05:06:48 INFO - 'USER': 'cltbld', 05:06:48 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733', 05:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:48 INFO - '_': '/tools/buildbot/bin/python'} 05:06:48 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/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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 05:06:48 INFO - mozversion application_buildid: 20170807112422 05:06:48 INFO - mozversion application_changeset: 47248637eafa9a38dade8dc3aa6c4736177c8d8d 05:06:48 INFO - mozversion application_display_name: Nightly 05:06:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:48 INFO - mozversion application_name: Firefox 05:06:48 INFO - mozversion application_remotingname: firefox 05:06:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:06:48 INFO - mozversion application_vendor: Mozilla 05:06:48 INFO - mozversion application_version: 57.0a1 05:06:48 INFO - mozversion platform_buildid: 20170807112422 05:06:48 INFO - mozversion platform_changeset: 47248637eafa9a38dade8dc3aa6c4736177c8d8d 05:06:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:06:48 INFO - mozversion platform_version: 57.0a1 05:06:48 INFO - using testdate: 1502107608 05:06:48 INFO - actual date: 1502107608 05:06:48 INFO - starting webserver on 'localhost:43488' 05:06:48 INFO - SUITE-START | Running 2 tests 05:06:48 INFO - TEST-START | dromaeo_css 05:06:48 INFO - Initialising browser for dromaeo_css test... 05:06:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43488/getInfo.html -profile /tmp/tmpWmY6tS/profile 05:06:48 INFO - TEST-INFO | started process 23682 (/builds/slave/test/build/application/firefox/firefox http://localhost:43488/getInfo.html) 05:06:55 INFO - TEST-INFO | 23682: exit 0 05:06:55 INFO - Browser initialized. 05:06:55 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:55 INFO - TEST-INFO | started process 23889 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWmY6tS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:56 INFO - PID 23889 | 05:06:56 INFO - PID 23889 | (/builds/slave/test/build/application/firefox/firefox:23938): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:06:56 INFO - PID 23889 | 05:07:06 INFO - PID 23889 | RSS: Main: 171339776 05:07:06 INFO - PID 23889 | 05:10:05 INFO - PID 23889 | Cycle 1(1): loaded http://localhost:43488/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43488/tests/dromaeo/cssquery-ext.html) 05:10:06 INFO - PID 23889 | RSS: Main: 173334528 05:10:06 INFO - PID 23889 | 05:13:08 INFO - PID 23889 | Cycle 1(1): loaded http://localhost:43488/tests/dromaeo/cssquery-ext.html (next: http://localhost:43488/tests/dromaeo/cssquery-jquery.html) 05:13:09 INFO - PID 23889 | RSS: Main: 170381312 05:13:09 INFO - PID 23889 | 05:16:10 INFO - PID 23889 | Cycle 1(1): loaded http://localhost:43488/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43488/tests/dromaeo/cssquery-mootools.html) 05:16:11 INFO - PID 23889 | RSS: Main: 172707840 05:16:11 INFO - PID 23889 | 05:19:13 INFO - PID 23889 | Cycle 1(1): loaded http://localhost:43488/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43488/tests/dromaeo/cssquery-prototype.html) 05:19:13 INFO - PID 23889 | RSS: Main: 171167744 05:19:13 INFO - PID 23889 | 05:22:17 INFO - PID 23889 | Cycle 1(1): loaded http://localhost:43488/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43488/tests/dromaeo/cssquery-yui.html) 05:22:18 INFO - PID 23889 | RSS: Main: 174231552 05:22:18 INFO - PID 23889 | 05:25:19 INFO - PID 23889 | Cycle 1(1): loaded http://localhost:43488/tests/dromaeo/cssquery-yui.html (next: http://localhost:43488/tests/dromaeo/cssquery-dojo.html) 05:25:20 INFO - PID 23889 | RSS: Main: 173412352 05:25:20 INFO - PID 23889 | 05:25:20 INFO - PID 23889 | __start_tp_report 05:25:20 INFO - PID 23889 | _x_x_mozilla_page_load 05:25:20 INFO - PID 23889 | _x_x_mozilla_page_load_details 05:25:20 INFO - PID 23889 | |i|pagename|runs| 05:25:20 INFO - PID 23889 | |0;dojo.html;3160.589123413957;3187.2509960159364;3198.4082944655256;3208.246062175389;3215.292635620163;6147.293061297952;6208;6220.751169953208;6250;6259.968700156499;6075.210222671058;6203.255609326876;6216.34728353522;6230.096635987779;6254.593451425665;5644.633098848582;5670;5774.07614781635;5796.3334216565;5862.970685146568;5736.713164341787;5796.362400135981;5798.217240672498;5821.708914554277;5838.824835254949;5463.699496527709;5658.74535645897;5659.773609055633;5738.971305143447;5765.3369862465515;5658.207850900877;5682.318121825366;5756.740946657411;5760.740766665468;5779.219805326311;5488.067028605107;5564.4713752193475;5616.831495055155;5659.320881494247;5740.7703691852275;6160.1991741073825;6172.938270617281;6207.751689932397;6211.87576248477;6224.502039836848;6728.889733194017;6764.763233286861;6768.221654509696;6771.559848609824;6777.186737591472;6729.764458243938;6754.3921047105505;6760.222574403957;6842.04211410403;6849.972504124371;6119.847003824895;6183.134361189437;6218.253809542839;6250.593711408789;6342.207224096988;6882.8967565486555;6947.756828510978;6953.82615434608;6961.686724097428;6971.895421568681;6577.473802095917;6656.966715166393;6669.766558170538;6676.599404035774;6682.832929176712;6677.76627818024;6693.062971183941;6733.6296503692765;6784.525083244224;6829.965850170816;6531.477481801445;6603.240627327918;6643.634600097041;6688;6728.394444500019;6692.531522793455;6704.363085506869;6705.932940670531;6713.26154123046;6717.764878229238;5451.863703407446;5478.753456094485;5490.615656904059;5514.062609356419;5516.420775818561;4643.955110100201;4681.7190968542;4713.575778179912;4718.363020992191;4738;5723.7996670116345;5780.21967034445;5792.768289268466;5819.272590926134;5874.706264686834;6577.6711164441585;6690.330966903368;6698.263191048981;6699.698513566901;6709.295523969913;5744.626599271034;5768.077107662753;5780.479756821906;5792.391798861143;5831.562632802556;5887.793927212527;5892.440218179266;5938.198343223696;5989.371116032754;6007.3992600739575;6383.648899310582;6469.45009674183;6498.772542960974;6526.379993900667;6540.2805691373915;6626.337366263335;6764.425023873026;6765.864682706417;6768.153980752406;6784.151981002375;6501.837454063686;6564.409203171737;6568.8357791055605;6570.47436205112;6577.934220657924;6679.465642748665;6693.230278518033;6707.295733947865;6720.361565651256;6722.899156512558;6729.327067293231;6739.73041078371;6816.659167041727;6837.487188460786;6846.520743548103;6492.188476440445;6510.609363438209;6601.207855057424;6625;6629.138212032598;6427;6495.772647957298;6506.577072490273;6511.218653761579;6515.73937042513;6420;6425.742970281136;6440.806775796734;6442.259140198749;6451.032345148077;5821.359650438533;6463.353664633499;6490;6537.934620653923;6543.836404089936;6380.330065343072;6390.329015453496;6418.358164183544;6447.516436267205;6458.806235812933 05:25:20 INFO - PID 23889 | |1;ext.html;792.29881554824;815.6207363575936;840.8150206954471;874.6982291109568;875;39421.605783942185;40117.59882401175;40229.79885100578;40240;40647;36642.63357366426;36844.07889802756;36892.446613300825;36901.815490922505;36934.26131477376;6716.462682985361;6827.078344423514;6829.829254268633;6835.58986460814;6844.623545704984;6667.599944003351;6757.898631520531;6823.3858952694245;6824.385805277524;6831.453483721315;6782.423494003016;7522.586257755821;7783.066032076131;7785.260400261966;7791.142974272825;7628.504147230467;7647.808804779869;7660;7685.961570192141;7721.575313357763;7446.255374462565;7530.548167109936;7600.657970391346;7626.771196864103;7639.236076392372;38420.23159536822;38691.61308386908;38782.41826372578;38857.417138742945;38862.805685971674;3540.6282340354405;3594.964050359489;3599.5140656011367;3601.1717305019993;3601.7838929664304;31156.22109447282;31384.21539461509;31525.842370788;31670.208244793834;31721.8413907929;4118.629323360882;4126.690498212646;4169.833206671729;4211.936820947688;4280.700350975433;188888;188933;192499;194315;204463;48368;48862;48897.26654100191;48936.26595601069;48973.265401019016;50454.74772626187;50800;50813;50821;50889.745551272004;16276.186190690418;16411.261493232832;16524.421645242357;16561.420350287925;16696.916515417346;13750.312484375741;13890.722185556435;13904.235267060409;13909.443622254996;13919.930400348136;14247.145171289549;14315.355808988621;14354.282285885873;14380.28098595087;14497.927510362591;5852.307938348407;5873.119032145127;5906.379830117861;5965.659230830749;5977.491913187341;1003.1373019203479;1019.592163134752;1020.6784862768204;1035.1460045462522;1035.4098164046572;22322.88838555797;22886.542269154525;22906;22955.196568120034;23116.768832311798;1110.400383792759;1113.3598181045932;1130.8190689489643;1131.7793030359167;1140.7433327501246;11868.690645330074;12207.755844883053;12303.396000598486;15970.920145399432;16322;228703;231084;231294;231378;232011;50317.496825032016;50487;50534.24198637023;50714.492855071716;50809.237861432106;1433.5126057140621;1457.1766951672482;1486.5911874234455;1491.5003473836168;1508.8189417269998;27111.322216944733;27469.725302747516;27482;27548.311292217855;27636;37669.81165094157;37811;37911.81044094872;37912.62087379201;37948;201904;204289;204411;204637;205297;32345.676543235208;35023.64976350204;36773.81613091917;37074.07314817151;37083.814580928;45605.77197113993;45681;45924;46213.768931156475;46261.224326539166;34296.65703342934;34327.4850877232;34401.65598343984;34545;35076.64923350734;33467.66532334743;33585.832070840464;34062.65937340595;34179.14552136217;34603.13492162716;36990.07524811794;37110.8144459276;37303.253934920605;37381.6261837389;37385.81307093556;32135.03594892157;32584.51123233106;32610.06446454488;32964.34071318608;33272;23782.643260350764;24300;24481.632775508024;24493.387665307797;24909.62635560432 05:25:20 INFO - PID 23889 | |2;jquery.html;558.3327923131859;565.1748447266992;566.6430149006126;582.5456144207518;589.887921294954;21734.56530869382;22780.658290125662;22864.428389290275;22866.428339291506;22912.541749165008;24261.63607545884;26973.86513067432;26979.730202697967;27020.729792702066;27034.32414189646;6543.14939057923;6672.466202703797;6680.966595167018;6684.331566843325;6697.8995315070315;6113.266408031042;6585.6048637081685;6627.966860165692;6634.867302653944;6645.534812563123;6352.6188428694195;6370.140031095789;6371.171747672796;6376.139221205124;6377.394147555998;6285.497160227171;6334.6199228046235;6342.238931328247;6349.111124442582;6355.142055822451;5903.203067585864;5907.232059832238;5909.349971503131;5917.14201440793;5954.106883967396;28001.57997630037;28970.27574310638;29251.56122658162;29563.70436295631;30222.697773022428;1907.904604769756;1958.0307747664997;1959.8922059286733;1959.9608007839763;1960.029785256293;18668.906655466773;19528.804711952842;19548.31580894676;19637.80362196374;19654.901725491425;2316.420894776306;2341.7658234176447;2365.231299827563;2421.9636705449434;2424.6726691896542;128501;132393;132506.337468313;132611;133132;14784.852151478348;15296;15408.61478463025;15472.458463953708;15986.040837549785;23569.410764730677;23621.409464763175;23776.16783412572;23788.643170352458;23861.284161475185;10239.232057595711;10585.470726463645;10589.258751887293;10648.574057037787;10720;9806.852897206547;9824.45965471906;9830.459324737209;9942.801143977225;9958.053984871425;8948.8657670135;9059.637614495346;9393.436393816393;9629.951850240705;9757.316987810927;2701.594760785898;2828.660560732725;2830.4197639483855;2845.1180134158476;2898.4637841999297;965.7247684409909;979.1041197304529;980.6714750558642;985.5564995751797;988.8566157907208;19541.902290488455;19561.706574401396;19877.60244795096;20226.089825957573;20387.592248154953;1040.7866387390566;1052.6947185316174;1053.0943388685723;1059.2161800267747;1065.8774240962234;10871;11163.888361116447;11242.887571124347;11296.830547541793;11489.138314626436;470970;474123;475858;476463;487395;111601.44199278952;113695.43152284352;114459.42770286095;115149;115332.42333788278;1233.6360773571819;1236.2644226685109;1263.8736126387287;1264.014069026161;1264.1530174782918;18057.54856128555;18073.54816129555;18286.35997740126;18310;18588.977606231787;51719.482805171465;51865.22202166895;52805;52994.2050869245;53139.734301328244;62183.37816621776;62974.68512657408;63790.68104659447;64113;64979.35020649733;14587.124772513684;14633.707325853211;14663.48677796272;14750.852491475378;14762.188079655722;23218.53562928698;23386.883065584563;23508.529829402974;23571.41071473227;23584.29247122589;18052.277908883498;18220.45338639843;18539.72190417118;18655.440336789918;18671.253149873854;14819.33313000939;14952.700945981236;15280.77078843796;15617.921910390376;16050.117243551716;17651.38220162288;18682.71975920335;18880.716789247897;19123.90438047801;19133.33033343826;14443.3500492476;14599.416023359372;14610.342534585756;14613.342399591831;14720.19038952868;17488.125593720517;17504.212310445804;18347.266109355478;18401.43587794999;18527.351542695942 05:25:20 INFO - PID 23889 | |3;mootools.html;1331.2611500617156;1396.0367346530886;1396.162302618429;1401.8037474753532;1410.4287763455804;13550.661233469169;14042.648933776634;14366.856331436682;14380.640483987892;14385.640358991017;14652.26738663068;14843;14877.330520126594;14910.701785964275;14931.552053438387;2676.157010541678;2744.807863449559;2747.738964798341;2750.3811642380506;2754.5730411786103;2603.06289735695;2625.396158883458;2661.840289582631;2716;2746.931326716838;2689.5965605159186;2734.7128551502105;2741.520233959059;2742.945141097177;2745.217612980301;2631.5394805908986;2635.973640263592;2642.3526236072285;2650.5096557136894;2652.5225459417393;2376.988115059431;2471.9134830280855;2478.9752102478924;2589.2879458148973;2590.8704564771874;14026.649333766636;14161.362738676784;14191.71616567663;14194.57416277503;14231.715365692731;7586.886196707053;7855.5286682799215;7907.367410607138;7910.9604451977375;7914.564698941556;4784.545468180518;5051.646384753069;5055.266986286968;5099.6685215460875;5133.6149788765615;1741;1768.054091061289;1789.8120697326724;1811.8369346758855;1816.7638207033;22885;23057;23078.192263270874;23083.307500775005;23395.76604233953;6525.249596296392;6536.248331441945;6555.05022739769;6722.563033402813;6770.72917083321;16255.593610159603;16275.91862040706;16278.918605406898;16291.674166516603;16299.266533006043;7588.165301816795;7755;7759.92240077592;7766.961165194138;7779.611019449118;7071.8939215911805;7072.8939065914055;7106.822329441702;7180.5691658500655;7242.275772422821;5098.566621837185;5184.351956005499;5234.162533994542;5253.474652534716;5279.498447647467;1407.73956817989;1446.428660679047;1497.2588568658366;1516.226724370579;1526.2445089680457;868.9217970382696;901.4636291406603;907.0476000199764;914.9542522873829;918.1231923513162;7758.340541054074;7903.525788452711;7982.481138725964;8002.159773223844;8032.959835200787;966.8356379415517;983.0464449483989;1000.1498726082917;1034.5086084110137;1038.672818062308;5222.92165617516;5400.256347595379;5434.538064264502;5502.312210973629;5556.027695153332;19274.22903083889;19591.706124408145;19876.90061549683;19920.203191872453;20018.399448016582;18722.531936701693;19629.803701963083;19767.60464790725;19768.20927162926;19785.40643780689;11024.228304018801;11110.888891110986;11320.490577924013;11401.600943967087;11412.828807567894;7961.522308661499;8107.959460202661;8109.472884262504;8148.470349427505;8228.670853166046;16527.586810329358;17052.829471705125;17434.389796357067;17445.389411370543;17487.562810929827;17600.823991759917;17632.73550896763;17790.110494475484;17822.10889455548;18087.728684069487;10549.367037977747;10585.947070264907;10725;10756.515956781806;10839.891601083888;13234.536791212262;13415.731685366432;13416.1279516835;13499.257540834955;13501.932490337878;11008;11018.449077546251;11039.83440248413;11142;11170.162237832357;10147.49262536885;10256.58973641046;10283.84574231402;10339.844902326622;10551.736206594896;8781.648734050566;9047.457152570867;9092.090790920854;9095.636174552943;9101.817963640822;7358.77923662291;7392.852142957003;7426.925730742623;7448.5158464699625;7464.589447580218;7203.279672032755;7260.346568808833;7277.672504737194;7280.781576552712;7382.114146302478 05:25:20 INFO - PID 23889 | |4;prototype.html;462.4127358255016;467.3947238326367;500.47200203785;506.3215291509377;510.1378670047619;6270.686465676721;6289.402506761856;6297.401746834049;6329.7151628176725;6354.202577054329;5966.701664916758;6051.5506161487965;6084.695765211733;6128.693565321727;6158.29179644341;4964.975175124137;4971.975140124295;4981.277714731362;5009.924851127227;5030.647854650176;4827.348307978431;4942.851714448554;4943.6539442239055;4993.675411098267;5032.9748351258195;4873.269009648546;4884.560389564938;4916.729579873106;4923.236898280772;4933.186024306003;4890.73100979446;4905.362302900636;4921.532454416824;4947.233178857283;4947.727874966893;4410.845620403271;4421.204183247014;4432.401625780511;4447.644188464915;4462.486814016364;6028.287270728146;6036.486898613623;6049.274087109531;6083.452489275942;6110.725017374184;5600.215969764236;5637.887242255132;5681.886362272773;5684.886302273972;5691.6585004899425;6390.297067322591;6477.352264773532;6545.770898018594;6560.802131286025;6573.572717773377;4803.495632958559;4822.300766388854;4827.831025914076;4844.612431005511;4847.903041939177;6797.048413222153;6818.693158807866;6853.7943861684225;6875.621840798754;6975.860482790366;4916.778744956486;4938.555530002317;4950.25746138075;4951.282064100686;4953.8018479261145;4982.078315511642;4995.450500444948;5011.273365362002;5014.473480284518;5031.345925029723;5614.775408983595;5618.775248989995;5633.774649013993;5651.237082993744;5678.4605462480995;6478.838029049217;6484;6531.902021469682;6545.541812073205;6566.835829104215;1653.7602047703015;1692.1454665394144;1705.780367037574;1727.0932760300943;1747.2748809244022;1162.7093226693328;1174.9060075193963;1200.3458115327167;1200.7598480303975;1216.5377156680404;5662.405447428043;5672.659640421588;5733.713314334266;5737.88524229513;5749.3963133871175;6445.291017988017;6536.901946470807;6666.133722382688;6681.926313895662;6766.834612923915;5706.80026199081;5776.45123713255;5781.335146458127;5807.767689292466;5828.795992140254;3527.171114788023;3539.8407071681836;3541.592716837545;3553.502509648651;3567.3935430976276;6459.61242325462;6500.512461565307;6507.316731743099;6516.837079073061;6549.672516374162;6819.352161544645;6872.072270243553;6928.1339832520935;6941.826454338581;6943;6417.711202995876;6440.645764482999;6471.158749362741;6472.320406357453;6489.9940509220205;6630.138082049302;6668.133142691419;6710.496712746662;6749.257581665915;6752.088468056946;6545.901811472927;6666.500012499179;6674.799756007327;6696.464282857457;6700.329967003261;6514.9348506514325;6529.67351632426;6554.770583029762;6564;6592.17597800275;4894.657373983787;4947.233178857283;4950.282209079735;4950.554450099508;4974.8756281093265;6510.73957041713;6572.934270657232;6658.636184154555;6714.597124172566;6756.898646520404;6035.796934119103;6379.4896408286295;6382.489400847828;6422.743090276524;6491.415772580491;6360.268569114611;6385.3295403983775;6388.552801303864;6466.32103629131;6512.544121911421;6341.302456729848;6356.68216589178;6362.172917520693;6430.324815894263;6484.697932233989;6594.967025164843;6610.4050635443045;6617.602943823386;6621.503387246072;6630.701618427279;6396.464848436435;6402.679866006588;6432.260290066515;6463.190306713939;6471.514636402195 05:25:20 INFO - PID 23889 | |5;yui.html;892.6206362296026;914.9725508234756;918.7427520294317;937.4609599480301;940.3182692547902;18400.723989140173;19280.51798705033;19291.807081929175;19294;19864.801351986476;21278.36164915055;21886.671699924515;21899.343019709435;21998.230061947834;22002.88998555009;986.04846323298;1023.0281232828805;1033.0805583031108;1037.3775734559279;1040.4745603470237;1010.2221289606998;1039.6880935719291;1044.7335929338008;1051.216843451626;1052.9262951593391;1119.1494464207221;1119.5186069989902;1120.3222050659356;1122.0182340452104;1123.2698745815203;1105.701460605632;1109.4341885638303;1111.644273832378;1112.2881355932209;1112.6050252160528;806.0730160315625;807.8182408957996;811.6144831205189;813.1624426840364;815.3273549321774;18579.256829726794;18681.43955681332;18754.1560629772;19017.524561886064;20443.2844850431;22091.226807061837;22519;22691.773082269297;22703.659445108173;22881.313560593208;16517.174141293006;16634.16829158537;16753.16234188298;16760.161991900357;16798.244079016473;3587.3004764071015;3641.453781932731;3642.8360723767496;3642.981785091084;3644.234710710754;126901;128092.35953820264;128126.3593682035;130589;131397;17057.658846822993;17520.64958700819;17541.736873946902;17548.38580649671;17654.823451765576;31921.361572768878;32178.517322240186;32489.35021299608;32955.83522082374;33256.1685957853;7646.502977306569;7946.880796788053;8492;8754.124587541195;8925.821483570422;4272.6154646082;4357.9782101089295;4362.912741745147;4386.188555117314;4387.561243875587;5239.187925871496;5333.359996800409;5335;5338.172583249525;5361.410244873061;1050.144132531984;1053.8893416191343;1075.714935542082;1075.8278675411896;1080.897314755097;656.8357910522369;667.6027763480721;687.5221720903924;690.5821977703517;691.6265216782981;11495.482703278372;11979.940100299442;12213.328266945404;12247.203931744409;12339.814902776465;683.361057411322;690.9101816763789;690.930906909305;709.51752808091;710.6233696141054;5443.237946687467;5475.288212532345;6308.3060863305;6346.2067241594805;6457.257415397289;107121;107478.46260768802;108621;109557.45221273843;115157;94012;96722.51638741902;96768.51615741877;99186;100354.49822750986;81264.59367703124;82848.8575571219;83244.58377708073;84342.57828710818;85068;13146.802797957847;13162.80255796183;13504.257265850472;13625.114367566077;13697.178169309474;37632.43551346787;37769.433458497595;37895.43156852705;38871.80564097274;39599.60400396075;47011.52988470208;48405.5159448401;49048.50951490439;49625;50082.49917500924;19173.712394313818;19562.706559401904;19605.11776970011;19904.800951990874;20590;18388.172532235814;18457.261709531467;18495.722564161817;18550.628987420445;18608.16263268129;19887.801121989174;19910.70133947963;19919.800801991794;20168.697469538263;20339.59320813546;19364.612707746048;19513.804861951197;19638.901805491452;19717.80282197217;19837.30569430063;18850.3402380916;19104.522386940436;19272.80727192766;19337;19506.21975121036;12745;12822.166559173624;12982.221066736027;13075.084744067824;13351.065425420127;14725.895557833419;14766.926165369105;14868.256587170381;14873.330700118304;15100.169490678118 05:25:20 INFO - PID 23889 | __end_tp_report 05:25:20 INFO - PID 23889 | __start_cc_report 05:25:20 INFO - PID 23889 | _x_x_mozilla_cycle_collect,373 05:25:20 INFO - PID 23889 | __end_cc_report 05:25:20 INFO - PID 23889 | __startTimestamp1502108720158__endTimestamp 05:25:20 INFO - PID 23889 | 05:25:20 INFO - PID 23889 | ------- Summary: start ------- 05:25:20 INFO - PID 23889 | Number of tests: 6 05:25:20 INFO - PID 23889 | 05:25:20 INFO - PID 23889 | [#0] dojo.html Cycles:165 Average:6174.74 Median:6427.00 stddev:728.53 (11.3%) stddev-sans-first:691.32 05:25:20 INFO - PID 23889 | Values: 3160.6 3187.3 3198.4 3208.2 3215.3 6147.3 6208.0 6220.8 6250.0 6260.0 6075.2 6203.3 6216.3 6230.1 6254.6 5644.6 5670.0 5774.1 5796.3 5863.0 5736.7 5796.4 5798.2 5821.7 5838.8 5463.7 5658.7 5659.8 5739.0 5765.3 5658.2 5682.3 5756.7 5760.7 5779.2 5488.1 5564.5 5616.8 5659.3 5740.8 6160.2 6172.9 6207.8 6211.9 6224.5 6728.9 6764.8 6768.2 6771.6 6777.2 6729.8 6754.4 6760.2 6842.0 6850.0 6119.8 6183.1 6218.3 6250.6 6342.2 6882.9 6947.8 6953.8 6961.7 6971.9 6577.5 6657.0 6669.8 6676.6 6682.8 6677.8 6693.1 6733.6 6784.5 6830.0 6531.5 6603.2 6643.6 6688.0 6728.4 6692.5 6704.4 6705.9 6713.3 6717.8 5451.9 5478.8 5490.6 5514.1 5516.4 4644.0 4681.7 4713.6 4718.4 4738.0 5723.8 5780.2 5792.8 5819.3 5874.7 6577.7 6690.3 6698.3 6699.7 6709.3 5744.6 5768.1 5780.5 5792.4 5831.6 5887.8 5892.4 5938.2 5989.4 6007.4 6383.6 6469.5 6498.8 6526.4 6540.3 6626.3 6764.4 6765.9 6768.2 6784.2 6501.8 6564.4 6568.8 6570.5 6577.9 6679.5 6693.2 6707.3 6720.4 6722.9 6729.3 6739.7 6816.7 6837.5 6846.5 6492.2 6510.6 6601.2 6625.0 6629.1 6427.0 6495.8 6506.6 6511.2 6515.7 6420.0 6425.7 6440.8 6442.3 6451.0 5821.4 6463.4 6490.0 6537.9 6543.8 6380.3 6390.3 6418.4 6447.5 6458.8 05:25:20 INFO - PID 23889 | 05:25:20 INFO - PID 23889 | [#1] ext.html Cycles:180 Average:38102.86 Median:3601.48 stddev:54407.48 (1510.7%) stddev-sans-first:54487.98 05:25:20 INFO - PID 23889 | Values: 792.3 815.6 840.8 874.7 875.0 39421.6 40117.6 40229.8 40240.0 40647.0 36642.6 36844.1 36892.4 36901.8 36934.3 6716.5 6827.1 6829.8 6835.6 6844.6 6667.6 6757.9 6823.4 6824.4 6831.5 6782.4 7522.6 7783.1 7785.3 7791.1 7628.5 7647.8 7660.0 7686.0 7721.6 7446.3 7530.5 7600.7 7626.8 7639.2 38420.2 38691.6 38782.4 38857.4 38862.8 3540.6 3595.0 3599.5 3601.2 3601.8 31156.2 31384.2 31525.8 31670.2 31721.8 4118.6 4126.7 4169.8 4211.9 4280.7 188888.0 188933.0 192499.0 194315.0 204463.0 48368.0 48862.0 48897.3 48936.3 48973.3 50454.7 50800.0 50813.0 50821.0 50889.7 16276.2 16411.3 16524.4 16561.4 16696.9 13750.3 13890.7 13904.2 13909.4 13919.9 14247.1 14315.4 14354.3 14380.3 14497.9 5852.3 5873.1 5906.4 5965.7 5977.5 1003.1 1019.6 1020.7 1035.1 1035.4 22322.9 22886.5 22906.0 22955.2 23116.8 1110.4 1113.4 1130.8 1131.8 1140.7 11868.7 12207.8 12303.4 15970.9 16322.0 228703.0 231084.0 231294.0 231378.0 232011.0 50317.5 50487.0 50534.2 50714.5 50809.2 1433.5 1457.2 1486.6 1491.5 1508.8 27111.3 27469.7 27482.0 27548.3 27636.0 37669.8 37811.0 37911.8 37912.6 37948.0 201904.0 204289.0 204411.0 204637.0 205297.0 32345.7 35023.6 36773.8 37074.1 37083.8 45605.8 45681.0 45924.0 46213.8 46261.2 34296.7 34327.5 34401.7 34545.0 35076.6 33467.7 33585.8 34062.7 34179.1 34603.1 36990.1 37110.8 37303.3 37381.6 37385.8 32135.0 32584.5 32610.1 32964.3 33272.0 23782.6 24300.0 24481.6 24493.4 24909.6 05:25:20 INFO - PID 23889 | 05:25:20 INFO - PID 23889 | [#2] jquery.html Cycles:180 Average:33916.49 Median:12767.48 stddev:80180.65 (628.0%) stddev-sans-first:80366.46 05:25:20 INFO - PID 23889 | Values: 558.3 565.2 566.6 582.5 589.9 21734.6 22780.7 22864.4 22866.4 22912.5 24261.6 26973.9 26979.7 27020.7 27034.3 6543.1 6672.5 6681.0 6684.3 6697.9 6113.3 6585.6 6628.0 6634.9 6645.5 6352.6 6370.1 6371.2 6376.1 6377.4 6285.5 6334.6 6342.2 6349.1 6355.1 5903.2 5907.2 5909.3 5917.1 5954.1 28001.6 28970.3 29251.6 29563.7 30222.7 1907.9 1958.0 1959.9 1960.0 1960.0 18668.9 19528.8 19548.3 19637.8 19654.9 2316.4 2341.8 2365.2 2422.0 2424.7 128501.0 132393.0 132506.3 132611.0 133132.0 14784.9 15296.0 15408.6 15472.5 15986.0 23569.4 23621.4 23776.2 23788.6 23861.3 10239.2 10585.5 10589.3 10648.6 10720.0 9806.9 9824.5 9830.5 9942.8 9958.1 8948.9 9059.6 9393.4 9630.0 9757.3 2701.6 2828.7 2830.4 2845.1 2898.5 965.7 979.1 980.7 985.6 988.9 19541.9 19561.7 19877.6 20226.1 20387.6 1040.8 1052.7 1053.1 1059.2 1065.9 10871.0 11163.9 11242.9 11296.8 11489.1 470970.0 474123.0 475858.0 476463.0 487395.0 111601.4 113695.4 114459.4 115149.0 115332.4 1233.6 1236.3 1263.9 1264.0 1264.2 18057.5 18073.5 18286.4 18310.0 18589.0 51719.5 51865.2 52805.0 52994.2 53139.7 62183.4 62974.7 63790.7 64113.0 64979.4 14587.1 14633.7 14663.5 14750.9 14762.2 23218.5 23386.9 23508.5 23571.4 23584.3 18052.3 18220.5 18539.7 18655.4 18671.3 14819.3 14952.7 15280.8 15617.9 16050.1 17651.4 18682.7 18880.7 19123.9 19133.3 14443.4 14599.4 14610.3 14613.3 14720.2 17488.1 17504.2 18347.3 18401.4 18527.4 05:25:20 INFO - PID 23889 | 05:25:20 INFO - PID 23889 | [#3] mootools.html Cycles:180 Average:8836.55 Median:2534.13 stddev:6032.05 (238.0%) stddev-sans-first:6022.61 05:25:20 INFO - PID 23889 | Values: 1331.3 1396.0 1396.2 1401.8 1410.4 13550.7 14042.6 14366.9 14380.6 14385.6 14652.3 14843.0 14877.3 14910.7 14931.6 2676.2 2744.8 2747.7 2750.4 2754.6 2603.1 2625.4 2661.8 2716.0 2746.9 2689.6 2734.7 2741.5 2742.9 2745.2 2631.5 2636.0 2642.4 2650.5 2652.5 2377.0 2471.9 2479.0 2589.3 2590.9 14026.6 14161.4 14191.7 14194.6 14231.7 7586.9 7855.5 7907.4 7911.0 7914.6 4784.5 5051.6 5055.3 5099.7 5133.6 1741.0 1768.1 1789.8 1811.8 1816.8 22885.0 23057.0 23078.2 23083.3 23395.8 6525.2 6536.2 6555.1 6722.6 6770.7 16255.6 16275.9 16278.9 16291.7 16299.3 7588.2 7755.0 7759.9 7767.0 7779.6 7071.9 7072.9 7106.8 7180.6 7242.3 5098.6 5184.4 5234.2 5253.5 5279.5 1407.7 1446.4 1497.3 1516.2 1526.2 868.9 901.5 907.0 915.0 918.1 7758.3 7903.5 7982.5 8002.2 8033.0 966.8 983.0 1000.1 1034.5 1038.7 5222.9 5400.3 5434.5 5502.3 5556.0 19274.2 19591.7 19876.9 19920.2 20018.4 18722.5 19629.8 19767.6 19768.2 19785.4 11024.2 11110.9 11320.5 11401.6 11412.8 7961.5 8108.0 8109.5 8148.5 8228.7 16527.6 17052.8 17434.4 17445.4 17487.6 17600.8 17632.7 17790.1 17822.1 18087.7 10549.4 10585.9 10725.0 10756.5 10839.9 13234.5 13415.7 13416.1 13499.3 13501.9 11008.0 11018.4 11039.8 11142.0 11170.2 10147.5 10256.6 10283.8 10339.8 10551.7 8781.6 9047.5 9092.1 9095.6 9101.8 7358.8 7392.9 7426.9 7448.5 7464.6 7203.3 7260.3 7277.7 7280.8 7382.1 05:25:20 INFO - PID 23889 | 05:25:20 INFO - PID 23889 | [#4] prototype.html Cycles:180 Average:5478.79 Median:6067.50 stddev:1548.83 (25.5%) stddev-sans-first:1506.72 05:25:20 INFO - PID 23889 | Values: 462.4 467.4 500.5 506.3 510.1 6270.7 6289.4 6297.4 6329.7 6354.2 5966.7 6051.6 6084.7 6128.7 6158.3 4965.0 4972.0 4981.3 5009.9 5030.6 4827.3 4942.9 4943.7 4993.7 5033.0 4873.3 4884.6 4916.7 4923.2 4933.2 4890.7 4905.4 4921.5 4947.2 4947.7 4410.8 4421.2 4432.4 4447.6 4462.5 6028.3 6036.5 6049.3 6083.5 6110.7 5600.2 5637.9 5681.9 5684.9 5691.7 6390.3 6477.4 6545.8 6560.8 6573.6 4803.5 4822.3 4827.8 4844.6 4847.9 6797.0 6818.7 6853.8 6875.6 6975.9 4916.8 4938.6 4950.3 4951.3 4953.8 4982.1 4995.5 5011.3 5014.5 5031.3 5614.8 5618.8 5633.8 5651.2 5678.5 6478.8 6484.0 6531.9 6545.5 6566.8 1653.8 1692.1 1705.8 1727.1 1747.3 1162.7 1174.9 1200.3 1200.8 1216.5 5662.4 5672.7 5733.7 5737.9 5749.4 6445.3 6536.9 6666.1 6681.9 6766.8 5706.8 5776.5 5781.3 5807.8 5828.8 3527.2 3539.8 3541.6 3553.5 3567.4 6459.6 6500.5 6507.3 6516.8 6549.7 6819.4 6872.1 6928.1 6941.8 6943.0 6417.7 6440.6 6471.2 6472.3 6490.0 6630.1 6668.1 6710.5 6749.3 6752.1 6545.9 6666.5 6674.8 6696.5 6700.3 6514.9 6529.7 6554.8 6564.0 6592.2 4894.7 4947.2 4950.3 4950.6 4974.9 6510.7 6572.9 6658.6 6714.6 6756.9 6035.8 6379.5 6382.5 6422.7 6491.4 6360.3 6385.3 6388.6 6466.3 6512.5 6341.3 6356.7 6362.2 6430.3 6484.7 6595.0 6610.4 6617.6 6621.5 6630.7 6396.5 6402.7 6432.3 6463.2 6471.5 05:25:20 INFO - PID 23889 | 05:25:20 INFO - PID 23889 | [#5] yui.html Cycles:180 Average:23405.82 Median:19851.05 stddev:31441.21 (158.4%) stddev-sans-first:31483.97 05:25:20 INFO - PID 23889 | Values: 892.6 915.0 918.7 937.5 940.3 18400.7 19280.5 19291.8 19294.0 19864.8 21278.4 21886.7 21899.3 21998.2 22002.9 986.0 1023.0 1033.1 1037.4 1040.5 1010.2 1039.7 1044.7 1051.2 1052.9 1119.1 1119.5 1120.3 1122.0 1123.3 1105.7 1109.4 1111.6 1112.3 1112.6 806.1 807.8 811.6 813.2 815.3 18579.3 18681.4 18754.2 19017.5 20443.3 22091.2 22519.0 22691.8 22703.7 22881.3 16517.2 16634.2 16753.2 16760.2 16798.2 3587.3 3641.5 3642.8 3643.0 3644.2 126901.0 128092.4 128126.4 130589.0 131397.0 17057.7 17520.6 17541.7 17548.4 17654.8 31921.4 32178.5 32489.4 32955.8 33256.2 7646.5 7946.9 8492.0 8754.1 8925.8 4272.6 4358.0 4362.9 4386.2 4387.6 5239.2 5333.4 5335.0 5338.2 5361.4 1050.1 1053.9 1075.7 1075.8 1080.9 656.8 667.6 687.5 690.6 691.6 11495.5 11979.9 12213.3 12247.2 12339.8 683.4 690.9 690.9 709.5 710.6 5443.2 5475.3 6308.3 6346.2 6457.3 107121.0 107478.5 108621.0 109557.5 115157.0 94012.0 96722.5 96768.5 99186.0 100354.5 81264.6 82848.9 83244.6 84342.6 85068.0 13146.8 13162.8 13504.3 13625.1 13697.2 37632.4 37769.4 37895.4 38871.8 39599.6 47011.5 48405.5 49048.5 49625.0 50082.5 19173.7 19562.7 19605.1 19904.8 20590.0 18388.2 18457.3 18495.7 18550.6 18608.2 19887.8 19910.7 19919.8 20168.7 20339.6 19364.6 19513.8 19638.9 19717.8 19837.3 18850.3 19104.5 19272.8 19337.0 19506.2 12745.0 12822.2 12982.2 13075.1 13351.1 14725.9 14766.9 14868.3 14873.3 15100.2 05:25:20 INFO - PID 23889 | -------- Summary: end -------- 05:25:20 INFO - PID 23889 | 05:25:20 INFO - TEST-INFO | 23889: exit 0 05:25:20 INFO - TEST-OK | dromaeo_css | took 1112162ms 05:25:20 INFO - TEST-START | kraken 05:25:20 INFO - Initialising browser for kraken test... 05:25:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:43488/getInfo.html -profile /tmp/tmpPpiCln/profile 05:25:20 INFO - TEST-INFO | started process 24254 (/builds/slave/test/build/application/firefox/firefox http://localhost:43488/getInfo.html) 05:25:27 INFO - TEST-INFO | 24254: exit 0 05:25:27 INFO - Browser initialized. 05:25:27 INFO - Running cycle 1/1 for kraken test... 05:25:27 INFO - TEST-INFO | started process 24464 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPpiCln/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:28 INFO - PID 24464 | 05:25:28 INFO - PID 24464 | (/builds/slave/test/build/application/firefox/firefox:24512): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:25:28 INFO - PID 24464 | 05:25:38 INFO - PID 24464 | RSS: Main: 171446272 05:25:38 INFO - PID 24464 | 05:25:41 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43488/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:42 INFO - PID 24464 | RSS: Main: 176205824 05:25:42 INFO - PID 24464 | 05:25:44 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43488/tests/kraken/driver.html?testName=audio-dft) 05:25:45 INFO - PID 24464 | RSS: Main: 176611328 05:25:45 INFO - PID 24464 | 05:25:48 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43488/tests/kraken/driver.html?testName=audio-fft) 05:25:49 INFO - PID 24464 | RSS: Main: 170913792 05:25:49 INFO - PID 24464 | 05:25:51 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43488/tests/kraken/driver.html?testName=audio-oscillator) 05:25:51 INFO - PID 24464 | RSS: Main: 171728896 05:25:51 INFO - PID 24464 | 05:25:54 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43488/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:54 INFO - PID 24464 | RSS: Main: 171778048 05:25:54 INFO - PID 24464 | 05:25:57 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43488/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:58 INFO - PID 24464 | RSS: Main: 174542848 05:25:58 INFO - PID 24464 | 05:26:01 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43488/tests/kraken/driver.html?testName=imaging-desaturate) 05:26:02 INFO - PID 24464 | RSS: Main: 175251456 05:26:02 INFO - PID 24464 | 05:26:05 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43488/tests/kraken/driver.html?testName=json-parse-financial) 05:26:06 INFO - PID 24464 | RSS: Main: 174931968 05:26:06 INFO - PID 24464 | 05:26:07 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43488/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:08 INFO - PID 24464 | RSS: Main: 175034368 05:26:08 INFO - PID 24464 | 05:26:09 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43488/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:10 INFO - PID 24464 | RSS: Main: 175022080 05:26:10 INFO - PID 24464 | 05:26:12 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43488/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:13 INFO - PID 24464 | RSS: Main: 171847680 05:26:13 INFO - PID 24464 | 05:26:16 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:16 INFO - PID 24464 | RSS: Main: 172765184 05:26:16 INFO - PID 24464 | 05:26:19 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:20 INFO - PID 24464 | RSS: Main: 172793856 05:26:20 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | Cycle 1(1): loaded http://localhost:43488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43488/tests/kraken/driver.html?testName=ai-astar) 05:26:22 INFO - PID 24464 | RSS: Main: 174215168 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | __start_tp_report 05:26:22 INFO - PID 24464 | _x_x_mozilla_page_load 05:26:22 INFO - PID 24464 | _x_x_mozilla_page_load_details 05:26:22 INFO - PID 24464 | |i|pagename|runs| 05:26:22 INFO - PID 24464 | |0;ai-astar;98;108;100;107;107;110;111;100;109;111 05:26:22 INFO - PID 24464 | |1;audio-beat-detection;140;118;154;178;117;145;146;122;176;123 05:26:22 INFO - PID 24464 | |2;audio-dft;163;163;164;164;169;163;165;175;162;163 05:26:22 INFO - PID 24464 | |3;audio-fft;75;75;76;174;77;73;74;90;94;75 05:26:22 INFO - PID 24464 | |4;audio-oscillator;78;79;126;79;79;79;138;78;78;138 05:26:22 INFO - PID 24464 | |5;imaging-gaussian-blur;114;115;115;116;115;114;115;115;114;117 05:26:22 INFO - PID 24464 | |6;imaging-darkroom;152;152;151;152;152;151;151;155;151;151 05:26:22 INFO - PID 24464 | |7;imaging-desaturate;95;95;95;95;95;95;99;94;95;95 05:26:22 INFO - PID 24464 | |8;json-parse-financial;53;53;54;52;53;53;53;53;53;53 05:26:22 INFO - PID 24464 | |9;json-stringify-tinderbox;54;54;54;54;57;54;54;54;54;54 05:26:22 INFO - PID 24464 | |10;stanford-crypto-aes;80;80;77;88;77;77;83;87;87;77 05:26:22 INFO - PID 24464 | |11;stanford-crypto-ccm;133;126;128;128;125;124;126;123;127;160 05:26:22 INFO - PID 24464 | |12;stanford-crypto-pbkdf2;175;179;176;176;188;174;183;177;171;171 05:26:22 INFO - PID 24464 | |13;stanford-crypto-sha256-iterative;67;65;65;66;64;67;64;66;75;63 05:26:22 INFO - PID 24464 | __end_tp_report 05:26:22 INFO - PID 24464 | __start_cc_report 05:26:22 INFO - PID 24464 | _x_x_mozilla_cycle_collect,983 05:26:22 INFO - PID 24464 | __end_cc_report 05:26:22 INFO - PID 24464 | __startTimestamp1502108782511__endTimestamp 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | ------- Summary: start ------- 05:26:22 INFO - PID 24464 | Number of tests: 14 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#0] ai-astar Cycles:10 Average:106.10 Median:109.50 stddev:4.91 (4.5%) stddev-sans-first:4.24 05:26:22 INFO - PID 24464 | Values: 98.0 108.0 100.0 107.0 107.0 110.0 111.0 100.0 109.0 111.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:145.50 stddev:22.58 (15.5%) stddev-sans-first:23.93 05:26:22 INFO - PID 24464 | Values: 140.0 118.0 154.0 178.0 117.0 145.0 146.0 122.0 176.0 123.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#2] audio-dft Cycles:10 Average:165.10 Median:164.00 stddev:3.98 (2.4%) stddev-sans-first:4.15 05:26:22 INFO - PID 24464 | Values: 163.0 163.0 164.0 164.0 169.0 163.0 165.0 175.0 162.0 163.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#3] audio-fft Cycles:10 Average:88.30 Median:75.50 stddev:30.96 (41.0%) stddev-sans-first:32.46 05:26:22 INFO - PID 24464 | Values: 75.0 75.0 76.0 174.0 77.0 73.0 74.0 90.0 94.0 75.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:78.50 stddev:26.98 (34.4%) stddev-sans-first:27.89 05:26:22 INFO - PID 24464 | Values: 78.0 79.0 126.0 79.0 79.0 79.0 138.0 78.0 78.0 138.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:115.00 stddev:0.94 (0.8%) stddev-sans-first:0.93 05:26:22 INFO - PID 24464 | Values: 114.0 115.0 115.0 116.0 115.0 114.0 115.0 115.0 114.0 117.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#6] imaging-darkroom Cycles:10 Average:151.80 Median:152.00 stddev:1.23 (0.8%) stddev-sans-first:1.30 05:26:22 INFO - PID 24464 | Values: 152.0 152.0 151.0 152.0 152.0 151.0 151.0 155.0 151.0 151.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:1.34 (1.4%) stddev-sans-first:1.41 05:26:22 INFO - PID 24464 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 99.0 94.0 95.0 95.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 05:26:22 INFO - PID 24464 | Values: 53.0 53.0 54.0 52.0 53.0 53.0 53.0 53.0 53.0 53.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#9] json-stringify-tinderbox Cycles:10 Average:54.30 Median:54.00 stddev:0.95 (1.8%) stddev-sans-first:1.00 05:26:22 INFO - PID 24464 | Values: 54.0 54.0 54.0 54.0 57.0 54.0 54.0 54.0 54.0 54.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.50 stddev:4.60 (5.6%) stddev-sans-first:4.85 05:26:22 INFO - PID 24464 | Values: 80.0 80.0 77.0 88.0 77.0 77.0 83.0 87.0 87.0 77.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#11] stanford-crypto-ccm Cycles:10 Average:130.00 Median:127.50 stddev:10.89 (8.5%) stddev-sans-first:11.50 05:26:22 INFO - PID 24464 | Values: 133.0 126.0 128.0 128.0 125.0 124.0 126.0 123.0 127.0 160.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:176.50 stddev:5.25 (3.0%) stddev-sans-first:5.52 05:26:22 INFO - PID 24464 | Values: 175.0 179.0 176.0 176.0 188.0 174.0 183.0 177.0 171.0 171.0 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:3.36 (5.1%) stddev-sans-first:3.55 05:26:22 INFO - PID 24464 | Values: 67.0 65.0 65.0 66.0 64.0 67.0 64.0 66.0 75.0 63.0 05:26:22 INFO - PID 24464 | -------- Summary: end -------- 05:26:22 INFO - PID 24464 | 05:26:22 INFO - PID 24464 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:26:23 INFO - TEST-INFO | 24464: exit 0 05:26:23 INFO - TEST-OK | kraken | took 62325ms 05:26:23 INFO - SUITE-END | took 1174s 05:26:23 INFO - Completed test suite (00:19:34) 05:26:23 INFO - javascript benchmark 05:26:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8501.201453625847, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3160.589123413957, 3187.2509960159364, 3198.4082944655256, 3208.246062175389, 3215.292635620163, 6147.293061297952, 6208.0, 6220.751169953208, 6250.0, 6259.968700156499, 6075.210222671058, 6203.255609326876, 6216.34728353522, 6230.096635987779, 6254.593451425665, 5644.633098848582, 5670.0, 5774.07614781635, 5796.3334216565, 5862.970685146568, 5736.713164341787, 5796.362400135981, 5798.217240672498, 5821.708914554277, 5838.824835254949, 5463.699496527709, 5658.74535645897, 5659.773609055633, 5738.971305143447, 5765.3369862465515, 5658.207850900877, 5682.318121825366, 5756.740946657411, 5760.740766665468, 5779.219805326311, 5488.067028605107, 5564.4713752193475, 5616.831495055155, 5659.320881494247, 5740.7703691852275, 6160.1991741073825, 6172.938270617281, 6207.751689932397, 6211.87576248477, 6224.502039836848, 6728.889733194017, 6764.763233286861, 6768.221654509696, 6771.559848609824, 6777.186737591472, 6729.764458243938, 6754.3921047105505, 6760.222574403957, 6842.04211410403, 6849.972504124371, 6119.847003824895, 6183.134361189437, 6218.253809542839, 6250.593711408789, 6342.207224096988, 6882.8967565486555, 6947.756828510978, 6953.82615434608, 6961.686724097428, 6971.895421568681, 6577.473802095917, 6656.966715166393, 6669.766558170538, 6676.599404035774, 6682.832929176712, 6677.76627818024, 6693.062971183941, 6733.6296503692765, 6784.525083244224, 6829.965850170816, 6531.477481801445, 6603.240627327918, 6643.634600097041, 6688.0, 6728.394444500019, 6692.531522793455, 6704.363085506869, 6705.932940670531, 6713.26154123046, 6717.764878229238, 5451.863703407446, 5478.753456094485, 5490.615656904059, 5514.062609356419, 5516.420775818561, 4643.955110100201, 4681.7190968542, 4713.575778179912, 4718.363020992191, 4738.0, 5723.7996670116345, 5780.21967034445, 5792.768289268466, 5819.272590926134, 5874.706264686834, 6577.6711164441585, 6690.330966903368, 6698.263191048981, 6699.698513566901, 6709.295523969913, 5744.626599271034, 5768.077107662753, 5780.479756821906, 5792.391798861143, 5831.562632802556, 5887.793927212527, 5892.440218179266, 5938.198343223696, 5989.371116032754, 6007.3992600739575, 6383.648899310582, 6469.45009674183, 6498.772542960974, 6526.379993900667, 6540.2805691373915, 6626.337366263335, 6764.425023873026, 6765.864682706417, 6768.153980752406, 6784.151981002375, 6501.837454063686, 6564.409203171737, 6568.8357791055605, 6570.47436205112, 6577.934220657924, 6679.465642748665, 6693.230278518033, 6707.295733947865, 6720.361565651256, 6722.899156512558, 6729.327067293231, 6739.73041078371, 6816.659167041727, 6837.487188460786, 6846.520743548103, 6492.188476440445, 6510.609363438209, 6601.207855057424, 6625.0, 6629.138212032598, 6427.0, 6495.772647957298, 6506.577072490273, 6511.218653761579, 6515.73937042513, 6420.0, 6425.742970281136, 6440.806775796734, 6442.259140198749, 6451.032345148077, 5821.359650438533, 6463.353664633499, 6490.0, 6537.934620653923, 6543.836404089936, 6380.330065343072, 6390.329015453496, 6418.358164183544, 6447.516436267205, 6458.806235812933], "value": 6120.6960011556885, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.29881554824, 815.6207363575936, 840.8150206954471, 874.6982291109568, 875.0, 39421.605783942185, 40117.59882401175, 40229.79885100578, 40240.0, 40647.0, 36642.63357366426, 36844.07889802756, 36892.446613300825, 36901.815490922505, 36934.26131477376, 6716.462682985361, 6827.078344423514, 6829.829254268633, 6835.58986460814, 6844.623545704984, 6667.599944003351, 6757.898631520531, 6823.3858952694245, 6824.385805277524, 6831.453483721315, 6782.423494003016, 7522.586257755821, 7783.066032076131, 7785.260400261966, 7791.142974272825, 7628.504147230467, 7647.808804779869, 7660.0, 7685.961570192141, 7721.575313357763, 7446.255374462565, 7530.548167109936, 7600.657970391346, 7626.771196864103, 7639.236076392372, 38420.23159536822, 38691.61308386908, 38782.41826372578, 38857.417138742945, 38862.805685971674, 3540.6282340354405, 3594.964050359489, 3599.5140656011367, 3601.1717305019993, 3601.7838929664304, 31156.22109447282, 31384.21539461509, 31525.842370788, 31670.208244793834, 31721.8413907929, 4118.629323360882, 4126.690498212646, 4169.833206671729, 4211.936820947688, 4280.700350975433, 188888.0, 188933.0, 192499.0, 194315.0, 204463.0, 48368.0, 48862.0, 48897.26654100191, 48936.26595601069, 48973.265401019016, 50454.74772626187, 50800.0, 50813.0, 50821.0, 50889.745551272004, 16276.186190690418, 16411.261493232832, 16524.421645242357, 16561.420350287925, 16696.916515417346, 13750.312484375741, 13890.722185556435, 13904.235267060409, 13909.443622254996, 13919.930400348136, 14247.145171289549, 14315.355808988621, 14354.282285885873, 14380.28098595087, 14497.927510362591, 5852.307938348407, 5873.119032145127, 5906.379830117861, 5965.659230830749, 5977.491913187341, 1003.1373019203479, 1019.592163134752, 1020.6784862768204, 1035.1460045462522, 1035.4098164046572, 22322.88838555797, 22886.542269154525, 22906.0, 22955.196568120034, 23116.768832311798, 1110.400383792759, 1113.3598181045932, 1130.8190689489643, 1131.7793030359167, 1140.7433327501246, 11868.690645330074, 12207.755844883053, 12303.396000598486, 15970.920145399432, 16322.0, 228703.0, 231084.0, 231294.0, 231378.0, 232011.0, 50317.496825032016, 50487.0, 50534.24198637023, 50714.492855071716, 50809.237861432106, 1433.5126057140621, 1457.1766951672482, 1486.5911874234455, 1491.5003473836168, 1508.8189417269998, 27111.322216944733, 27469.725302747516, 27482.0, 27548.311292217855, 27636.0, 37669.81165094157, 37811.0, 37911.81044094872, 37912.62087379201, 37948.0, 201904.0, 204289.0, 204411.0, 204637.0, 205297.0, 32345.676543235208, 35023.64976350204, 36773.81613091917, 37074.07314817151, 37083.814580928, 45605.77197113993, 45681.0, 45924.0, 46213.768931156475, 46261.224326539166, 34296.65703342934, 34327.4850877232, 34401.65598343984, 34545.0, 35076.64923350734, 33467.66532334743, 33585.832070840464, 34062.65937340595, 34179.14552136217, 34603.13492162716, 36990.07524811794, 37110.8144459276, 37303.253934920605, 37381.6261837389, 37385.81307093556, 32135.03594892157, 32584.51123233106, 32610.06446454488, 32964.34071318608, 33272.0, 23782.643260350764, 24300.0, 24481.632775508024, 24493.387665307797, 24909.62635560432], "value": 17234.22765108284, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.3327923131859, 565.1748447266992, 566.6430149006126, 582.5456144207518, 589.887921294954, 21734.56530869382, 22780.658290125662, 22864.428389290275, 22866.428339291506, 22912.541749165008, 24261.63607545884, 26973.86513067432, 26979.730202697967, 27020.729792702066, 27034.32414189646, 6543.14939057923, 6672.466202703797, 6680.966595167018, 6684.331566843325, 6697.8995315070315, 6113.266408031042, 6585.6048637081685, 6627.966860165692, 6634.867302653944, 6645.534812563123, 6352.6188428694195, 6370.140031095789, 6371.171747672796, 6376.139221205124, 6377.394147555998, 6285.497160227171, 6334.6199228046235, 6342.238931328247, 6349.111124442582, 6355.142055822451, 5903.203067585864, 5907.232059832238, 5909.349971503131, 5917.14201440793, 5954.106883967396, 28001.57997630037, 28970.27574310638, 29251.56122658162, 29563.70436295631, 30222.697773022428, 1907.904604769756, 1958.0307747664997, 1959.8922059286733, 1959.9608007839763, 1960.029785256293, 18668.906655466773, 19528.804711952842, 19548.31580894676, 19637.80362196374, 19654.901725491425, 2316.420894776306, 2341.7658234176447, 2365.231299827563, 2421.9636705449434, 2424.6726691896542, 128501.0, 132393.0, 132506.337468313, 132611.0, 133132.0, 14784.852151478348, 15296.0, 15408.61478463025, 15472.458463953708, 15986.040837549785, 23569.410764730677, 23621.409464763175, 23776.16783412572, 23788.643170352458, 23861.284161475185, 10239.232057595711, 10585.470726463645, 10589.258751887293, 10648.574057037787, 10720.0, 9806.852897206547, 9824.45965471906, 9830.459324737209, 9942.801143977225, 9958.053984871425, 8948.8657670135, 9059.637614495346, 9393.436393816393, 9629.951850240705, 9757.316987810927, 2701.594760785898, 2828.660560732725, 2830.4197639483855, 2845.1180134158476, 2898.4637841999297, 965.7247684409909, 979.1041197304529, 980.6714750558642, 985.5564995751797, 988.8566157907208, 19541.902290488455, 19561.706574401396, 19877.60244795096, 20226.089825957573, 20387.592248154953, 1040.7866387390566, 1052.6947185316174, 1053.0943388685723, 1059.2161800267747, 1065.8774240962234, 10871.0, 11163.888361116447, 11242.887571124347, 11296.830547541793, 11489.138314626436, 470970.0, 474123.0, 475858.0, 476463.0, 487395.0, 111601.44199278952, 113695.43152284352, 114459.42770286095, 115149.0, 115332.42333788278, 1233.6360773571819, 1236.2644226685109, 1263.8736126387287, 1264.014069026161, 1264.1530174782918, 18057.54856128555, 18073.54816129555, 18286.35997740126, 18310.0, 18588.977606231787, 51719.482805171465, 51865.22202166895, 52805.0, 52994.2050869245, 53139.734301328244, 62183.37816621776, 62974.68512657408, 63790.68104659447, 64113.0, 64979.35020649733, 14587.124772513684, 14633.707325853211, 14663.48677796272, 14750.852491475378, 14762.188079655722, 23218.53562928698, 23386.883065584563, 23508.529829402974, 23571.41071473227, 23584.29247122589, 18052.277908883498, 18220.45338639843, 18539.72190417118, 18655.440336789918, 18671.253149873854, 14819.33313000939, 14952.700945981236, 15280.77078843796, 15617.921910390376, 16050.117243551716, 17651.38220162288, 18682.71975920335, 18880.716789247897, 19123.90438047801, 19133.33033343826, 14443.3500492476, 14599.416023359372, 14610.342534585756, 14613.342399591831, 14720.19038952868, 17488.125593720517, 17504.212310445804, 18347.266109355478, 18401.43587794999, 18527.351542695942], "value": 11927.559623188166, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.2611500617156, 1396.0367346530886, 1396.162302618429, 1401.8037474753532, 1410.4287763455804, 13550.661233469169, 14042.648933776634, 14366.856331436682, 14380.640483987892, 14385.640358991017, 14652.26738663068, 14843.0, 14877.330520126594, 14910.701785964275, 14931.552053438387, 2676.157010541678, 2744.807863449559, 2747.738964798341, 2750.3811642380506, 2754.5730411786103, 2603.06289735695, 2625.396158883458, 2661.840289582631, 2716.0, 2746.931326716838, 2689.5965605159186, 2734.7128551502105, 2741.520233959059, 2742.945141097177, 2745.217612980301, 2631.5394805908986, 2635.973640263592, 2642.3526236072285, 2650.5096557136894, 2652.5225459417393, 2376.988115059431, 2471.9134830280855, 2478.9752102478924, 2589.2879458148973, 2590.8704564771874, 14026.649333766636, 14161.362738676784, 14191.71616567663, 14194.57416277503, 14231.715365692731, 7586.886196707053, 7855.5286682799215, 7907.367410607138, 7910.9604451977375, 7914.564698941556, 4784.545468180518, 5051.646384753069, 5055.266986286968, 5099.6685215460875, 5133.6149788765615, 1741.0, 1768.054091061289, 1789.8120697326724, 1811.8369346758855, 1816.7638207033, 22885.0, 23057.0, 23078.192263270874, 23083.307500775005, 23395.76604233953, 6525.249596296392, 6536.248331441945, 6555.05022739769, 6722.563033402813, 6770.72917083321, 16255.593610159603, 16275.91862040706, 16278.918605406898, 16291.674166516603, 16299.266533006043, 7588.165301816795, 7755.0, 7759.92240077592, 7766.961165194138, 7779.611019449118, 7071.8939215911805, 7072.8939065914055, 7106.822329441702, 7180.5691658500655, 7242.275772422821, 5098.566621837185, 5184.351956005499, 5234.162533994542, 5253.474652534716, 5279.498447647467, 1407.73956817989, 1446.428660679047, 1497.2588568658366, 1516.226724370579, 1526.2445089680457, 868.9217970382696, 901.4636291406603, 907.0476000199764, 914.9542522873829, 918.1231923513162, 7758.340541054074, 7903.525788452711, 7982.481138725964, 8002.159773223844, 8032.959835200787, 966.8356379415517, 983.0464449483989, 1000.1498726082917, 1034.5086084110137, 1038.672818062308, 5222.92165617516, 5400.256347595379, 5434.538064264502, 5502.312210973629, 5556.027695153332, 19274.22903083889, 19591.706124408145, 19876.90061549683, 19920.203191872453, 20018.399448016582, 18722.531936701693, 19629.803701963083, 19767.60464790725, 19768.20927162926, 19785.40643780689, 11024.228304018801, 11110.888891110986, 11320.490577924013, 11401.600943967087, 11412.828807567894, 7961.522308661499, 8107.959460202661, 8109.472884262504, 8148.470349427505, 8228.670853166046, 16527.586810329358, 17052.829471705125, 17434.389796357067, 17445.389411370543, 17487.562810929827, 17600.823991759917, 17632.73550896763, 17790.110494475484, 17822.10889455548, 18087.728684069487, 10549.367037977747, 10585.947070264907, 10725.0, 10756.515956781806, 10839.891601083888, 13234.536791212262, 13415.731685366432, 13416.1279516835, 13499.257540834955, 13501.932490337878, 11008.0, 11018.449077546251, 11039.83440248413, 11142.0, 11170.162237832357, 10147.49262536885, 10256.58973641046, 10283.84574231402, 10339.844902326622, 10551.736206594896, 8781.648734050566, 9047.457152570867, 9092.090790920854, 9095.636174552943, 9101.817963640822, 7358.77923662291, 7392.852142957003, 7426.925730742623, 7448.5158464699625, 7464.589447580218, 7203.279672032755, 7260.346568808833, 7277.672504737194, 7280.781576552712, 7382.114146302478], "value": 6445.620061579229, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.4127358255016, 467.3947238326367, 500.47200203785, 506.3215291509377, 510.1378670047619, 6270.686465676721, 6289.402506761856, 6297.401746834049, 6329.7151628176725, 6354.202577054329, 5966.701664916758, 6051.5506161487965, 6084.695765211733, 6128.693565321727, 6158.29179644341, 4964.975175124137, 4971.975140124295, 4981.277714731362, 5009.924851127227, 5030.647854650176, 4827.348307978431, 4942.851714448554, 4943.6539442239055, 4993.675411098267, 5032.9748351258195, 4873.269009648546, 4884.560389564938, 4916.729579873106, 4923.236898280772, 4933.186024306003, 4890.73100979446, 4905.362302900636, 4921.532454416824, 4947.233178857283, 4947.727874966893, 4410.845620403271, 4421.204183247014, 4432.401625780511, 4447.644188464915, 4462.486814016364, 6028.287270728146, 6036.486898613623, 6049.274087109531, 6083.452489275942, 6110.725017374184, 5600.215969764236, 5637.887242255132, 5681.886362272773, 5684.886302273972, 5691.6585004899425, 6390.297067322591, 6477.352264773532, 6545.770898018594, 6560.802131286025, 6573.572717773377, 4803.495632958559, 4822.300766388854, 4827.831025914076, 4844.612431005511, 4847.903041939177, 6797.048413222153, 6818.693158807866, 6853.7943861684225, 6875.621840798754, 6975.860482790366, 4916.778744956486, 4938.555530002317, 4950.25746138075, 4951.282064100686, 4953.8018479261145, 4982.078315511642, 4995.450500444948, 5011.273365362002, 5014.473480284518, 5031.345925029723, 5614.775408983595, 5618.775248989995, 5633.774649013993, 5651.237082993744, 5678.4605462480995, 6478.838029049217, 6484.0, 6531.902021469682, 6545.541812073205, 6566.835829104215, 1653.7602047703015, 1692.1454665394144, 1705.780367037574, 1727.0932760300943, 1747.2748809244022, 1162.7093226693328, 1174.9060075193963, 1200.3458115327167, 1200.7598480303975, 1216.5377156680404, 5662.405447428043, 5672.659640421588, 5733.713314334266, 5737.88524229513, 5749.3963133871175, 6445.291017988017, 6536.901946470807, 6666.133722382688, 6681.926313895662, 6766.834612923915, 5706.80026199081, 5776.45123713255, 5781.335146458127, 5807.767689292466, 5828.795992140254, 3527.171114788023, 3539.8407071681836, 3541.592716837545, 3553.502509648651, 3567.3935430976276, 6459.61242325462, 6500.512461565307, 6507.316731743099, 6516.837079073061, 6549.672516374162, 6819.352161544645, 6872.072270243553, 6928.1339832520935, 6941.826454338581, 6943.0, 6417.711202995876, 6440.645764482999, 6471.158749362741, 6472.320406357453, 6489.9940509220205, 6630.138082049302, 6668.133142691419, 6710.496712746662, 6749.257581665915, 6752.088468056946, 6545.901811472927, 6666.500012499179, 6674.799756007327, 6696.464282857457, 6700.329967003261, 6514.9348506514325, 6529.67351632426, 6554.770583029762, 6564.0, 6592.17597800275, 4894.657373983787, 4947.233178857283, 4950.282209079735, 4950.554450099508, 4974.8756281093265, 6510.73957041713, 6572.934270657232, 6658.636184154555, 6714.597124172566, 6756.898646520404, 6035.796934119103, 6379.4896408286295, 6382.489400847828, 6422.743090276524, 6491.415772580491, 6360.268569114611, 6385.3295403983775, 6388.552801303864, 6466.32103629131, 6512.544121911421, 6341.302456729848, 6356.68216589178, 6362.172917520693, 6430.324815894263, 6484.697932233989, 6594.967025164843, 6610.4050635443045, 6617.602943823386, 6621.503387246072, 6630.701618427279, 6396.464848436435, 6402.679866006588, 6432.260290066515, 6463.190306713939, 6471.514636402195], "value": 5015.522178108921, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.6206362296026, 914.9725508234756, 918.7427520294317, 937.4609599480301, 940.3182692547902, 18400.723989140173, 19280.51798705033, 19291.807081929175, 19294.0, 19864.801351986476, 21278.36164915055, 21886.671699924515, 21899.343019709435, 21998.230061947834, 22002.88998555009, 986.04846323298, 1023.0281232828805, 1033.0805583031108, 1037.3775734559279, 1040.4745603470237, 1010.2221289606998, 1039.6880935719291, 1044.7335929338008, 1051.216843451626, 1052.9262951593391, 1119.1494464207221, 1119.5186069989902, 1120.3222050659356, 1122.0182340452104, 1123.2698745815203, 1105.701460605632, 1109.4341885638303, 1111.644273832378, 1112.2881355932209, 1112.6050252160528, 806.0730160315625, 807.8182408957996, 811.6144831205189, 813.1624426840364, 815.3273549321774, 18579.256829726794, 18681.43955681332, 18754.1560629772, 19017.524561886064, 20443.2844850431, 22091.226807061837, 22519.0, 22691.773082269297, 22703.659445108173, 22881.313560593208, 16517.174141293006, 16634.16829158537, 16753.16234188298, 16760.161991900357, 16798.244079016473, 3587.3004764071015, 3641.453781932731, 3642.8360723767496, 3642.981785091084, 3644.234710710754, 126901.0, 128092.35953820264, 128126.3593682035, 130589.0, 131397.0, 17057.658846822993, 17520.64958700819, 17541.736873946902, 17548.38580649671, 17654.823451765576, 31921.361572768878, 32178.517322240186, 32489.35021299608, 32955.83522082374, 33256.1685957853, 7646.502977306569, 7946.880796788053, 8492.0, 8754.124587541195, 8925.821483570422, 4272.6154646082, 4357.9782101089295, 4362.912741745147, 4386.188555117314, 4387.561243875587, 5239.187925871496, 5333.359996800409, 5335.0, 5338.172583249525, 5361.410244873061, 1050.144132531984, 1053.8893416191343, 1075.714935542082, 1075.8278675411896, 1080.897314755097, 656.8357910522369, 667.6027763480721, 687.5221720903924, 690.5821977703517, 691.6265216782981, 11495.482703278372, 11979.940100299442, 12213.328266945404, 12247.203931744409, 12339.814902776465, 683.361057411322, 690.9101816763789, 690.930906909305, 709.51752808091, 710.6233696141054, 5443.237946687467, 5475.288212532345, 6308.3060863305, 6346.2067241594805, 6457.257415397289, 107121.0, 107478.46260768802, 108621.0, 109557.45221273843, 115157.0, 94012.0, 96722.51638741902, 96768.51615741877, 99186.0, 100354.49822750986, 81264.59367703124, 82848.8575571219, 83244.58377708073, 84342.57828710818, 85068.0, 13146.802797957847, 13162.80255796183, 13504.257265850472, 13625.114367566077, 13697.178169309474, 37632.43551346787, 37769.433458497595, 37895.43156852705, 38871.80564097274, 39599.60400396075, 47011.52988470208, 48405.5159448401, 49048.50951490439, 49625.0, 50082.49917500924, 19173.712394313818, 19562.706559401904, 19605.11776970011, 19904.800951990874, 20590.0, 18388.172532235814, 18457.261709531467, 18495.722564161817, 18550.628987420445, 18608.16263268129, 19887.801121989174, 19910.70133947963, 19919.800801991794, 20168.697469538263, 20339.59320813546, 19364.612707746048, 19513.804861951197, 19638.901805491452, 19717.80282197217, 19837.30569430063, 18850.3402380916, 19104.522386940436, 19272.80727192766, 19337.0, 19506.21975121036, 12745.0, 12822.166559173624, 12982.221066736027, 13075.084744067824, 13351.065425420127, 14725.895557833419, 14766.926165369105, 14868.256587170381, 14873.330700118304, 15100.169490678118], "value": 9279.61476166633, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 108.0, 100.0, 107.0, 107.0, 110.0, 111.0, 100.0, 109.0, 111.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 118.0, 154.0, 178.0, 117.0, 145.0, 146.0, 122.0, 176.0, 123.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 164.0, 164.0, 169.0, 163.0, 165.0, 175.0, 162.0, 163.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 174.0, 77.0, 73.0, 74.0, 90.0, 94.0, 75.0], "value": 88.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 126.0, 79.0, 79.0, 79.0, 138.0, 78.0, 78.0, 138.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 116.0, 115.0, 114.0, 115.0, 115.0, 114.0, 117.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 151.0, 152.0, 152.0, 151.0, 151.0, 155.0, 151.0, 151.0], "value": 151.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 99.0, 94.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 52.0, 53.0, 53.0, 53.0, 53.0, 53.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 57.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 77.0, 88.0, 77.0, 77.0, 83.0, 87.0, 87.0, 77.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 126.0, 128.0, 128.0, 125.0, 124.0, 126.0, 123.0, 127.0, 160.0], "value": 130.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 179.0, 176.0, 176.0, 188.0, 174.0, 183.0, 177.0, 171.0, 171.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 66.0, 64.0, 67.0, 64.0, 66.0, 75.0, 63.0], "value": 66.2, "unit": "score"}]}]} 05:26:23 INFO - Return code: 0 05:26:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:26:23 INFO - # TBPL SUCCESS # 05:26:23 INFO - Running post-action listener: _package_coverage_data 05:26:23 INFO - Running post-action listener: _resource_record_post_action 05:26:23 INFO - [mozharness: 2017-08-07 12:26:23.791486Z] Finished run-tests step (success) 05:26:23 INFO - Running post-run listener: _resource_record_post_run 05:26:23 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 40960; Write bytes: 426905600; Read time: 104; Write time: 601776 05:26:23 INFO - TinderboxPrint: CPU usage
13.2% 05:26:23 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 104 05:26:23 INFO - TinderboxPrint: I/O write bytes / time
426,905,600 / 601,776 05:26:23 INFO - TinderboxPrint: CPU idle
8,259.6 (86.8%) 05:26:23 INFO - TinderboxPrint: CPU user
1,200.8 (12.6%) 05:26:23 INFO - TinderboxPrint: Swap in / out
0 / 0 05:26:23 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 106242048; Read time: 0; Write time: 165768 05:26:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:26:23 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 36864; Write bytes: 320167936; Read time: 60; Write time: 435908 05:26:24 INFO - Running post-run listener: _upload_blobber_files 05:26:24 INFO - Blob upload gear active. 05:26:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:26:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:26:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:26:24 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:26:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:26:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:31 INFO - (blobuploader) - INFO - Done attempting. 05:26:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:26:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:26:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:32 INFO - (blobuploader) - INFO - Done attempting. 05:26:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:26:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:26:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:33 INFO - (blobuploader) - INFO - Done attempting. 05:26:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:26:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:26:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:34 INFO - (blobuploader) - INFO - Done attempting. 05:26:34 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:34 INFO - Return code: 0 05:26:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:34 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7016b6379e9e4aab44ac147e10fe4aff17131b87872da3a27211dd2e215cf9756c6bd545ac956b52c7cecf138c678909bb9ec5a90717084c851b920d6cbef17d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3930d9cd1636af0a3309a5bc4d794c1dc7731df7b2aa2b3be14c09dfec8ffcccff8972355117cbca5269ff1bad5f66bb16ae4a88a87b9be9a1ce9c3bbe21518f", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5bf5fb09d3e124fa757eb8defefb6c40c46964b913008daba044d26c00dd567e22df1411af7f260b52ed90991ef3a265d3dda4ceb65774d52829de5fec2ce9e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b80d08dc2138bcdb855f55caaba84987f0d82614cdb94a580b90f6f7ecc59d049c7b06a2857b7329513781fa789bef847259691be5096a83b8153e292ccf0df9"} 05:26:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:34 INFO - Contents: 05:26:34 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7016b6379e9e4aab44ac147e10fe4aff17131b87872da3a27211dd2e215cf9756c6bd545ac956b52c7cecf138c678909bb9ec5a90717084c851b920d6cbef17d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3930d9cd1636af0a3309a5bc4d794c1dc7731df7b2aa2b3be14c09dfec8ffcccff8972355117cbca5269ff1bad5f66bb16ae4a88a87b9be9a1ce9c3bbe21518f", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5bf5fb09d3e124fa757eb8defefb6c40c46964b913008daba044d26c00dd567e22df1411af7f260b52ed90991ef3a265d3dda4ceb65774d52829de5fec2ce9e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b80d08dc2138bcdb855f55caaba84987f0d82614cdb94a580b90f6f7ecc59d049c7b06a2857b7329513781fa789bef847259691be5096a83b8153e292ccf0df9"} 05:26:34 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:34 INFO - Copying logs to upload dir... 05:26:34 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.807309 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-07 05:26:34.355911) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-07 05:26:34.358992) ========= 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=eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733 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/7016b6379e9e4aab44ac147e10fe4aff17131b87872da3a27211dd2e215cf9756c6bd545ac956b52c7cecf138c678909bb9ec5a90717084c851b920d6cbef17d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3930d9cd1636af0a3309a5bc4d794c1dc7731df7b2aa2b3be14c09dfec8ffcccff8972355117cbca5269ff1bad5f66bb16ae4a88a87b9be9a1ce9c3bbe21518f", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5bf5fb09d3e124fa757eb8defefb6c40c46964b913008daba044d26c00dd567e22df1411af7f260b52ed90991ef3a265d3dda4ceb65774d52829de5fec2ce9e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b80d08dc2138bcdb855f55caaba84987f0d82614cdb94a580b90f6f7ecc59d049c7b06a2857b7329513781fa789bef847259691be5096a83b8153e292ccf0df9"} build_url:https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011379 build_url: 'https://queue.taskcluster.net/v1/task/FK6Vw9RDTvi4Ea5oCnVSGw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7016b6379e9e4aab44ac147e10fe4aff17131b87872da3a27211dd2e215cf9756c6bd545ac956b52c7cecf138c678909bb9ec5a90717084c851b920d6cbef17d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3930d9cd1636af0a3309a5bc4d794c1dc7731df7b2aa2b3be14c09dfec8ffcccff8972355117cbca5269ff1bad5f66bb16ae4a88a87b9be9a1ce9c3bbe21518f", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5bf5fb09d3e124fa757eb8defefb6c40c46964b913008daba044d26c00dd567e22df1411af7f260b52ed90991ef3a265d3dda4ceb65774d52829de5fec2ce9e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b80d08dc2138bcdb855f55caaba84987f0d82614cdb94a580b90f6f7ecc59d049c7b06a2857b7329513781fa789bef847259691be5096a83b8153e292ccf0df9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-07 05:26:34.389231) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:26:34.389568) ========= 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=eca2ab57546de7b41f256b19000001ef-1502107535.619501-282363733 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004406 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:26:34.474751) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-07 05:26:34.475076) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-07 05:26:34.475379) ========= ========= Total master_lag: 0.62 =========