builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-086 starttime: 1502906450.67 results: success (0) revision: 07ce8c96222d533fc89c02802143d35d7c351f9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:50.669280) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:50.669851) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:50.673514) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:50.691529) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:50.691952) ========= 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=438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-16 11:00:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.9M=0s 2017-08-16 11:00:50 (69.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.173356 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:50.879576) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:50.879899) ========= 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=438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022891 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:50.935159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-16 11:00:50.935579) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 07ce8c96222d533fc89c02802143d35d7c351f9c --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 07ce8c96222d533fc89c02802143d35d7c351f9c --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=438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-16 11:00:50,906 truncating revision to first 12 chars 2017-08-16 11:00:50,906 Setting DEBUG logging. 2017-08-16 11:00:50,906 attempt 1/10 2017-08-16 11:00:50,906 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/07ce8c96222d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-16 11:00:51,958 unpacking tar archive at: mozilla-central-07ce8c96222d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.372845 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-16 11:00:52.326131) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:52.326574) ========= script_repo_revision: 07ce8c96222d533fc89c02802143d35d7c351f9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:52.326984) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:52.327262) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 11:00:52.340423) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-08-16 11:00:52.340830) ========= /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=438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:00:52 INFO - MultiFileLogger online at 20170816 11:00:52 in /builds/slave/test 11:00:52 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 11:00:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:00:52 INFO - {'append_to_log': False, 11:00:52 INFO - 'base_work_dir': '/builds/slave/test', 11:00:52 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:00:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:00:52 INFO - 'branch': 'Firefox-Non-PGO', 11:00:52 INFO - 'buildbot_json_path': 'buildprops.json', 11:00:52 INFO - 'code_coverage': False, 11:00:52 INFO - 'config_files': ('talos/linux_config.py',), 11:00:52 INFO - 'default_actions': ('clobber', 11:00:52 INFO - 'read-buildbot-config', 11:00:52 INFO - 'download-and-extract', 11:00:52 INFO - 'populate-webroot', 11:00:52 INFO - 'create-virtualenv', 11:00:52 INFO - 'install', 11:00:52 INFO - 'setup-mitmproxy', 11:00:52 INFO - 'run-tests'), 11:00:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:00:52 INFO - 'disable_ccov_upload': False, 11:00:52 INFO - 'download_minidump_stackwalk': True, 11:00:52 INFO - 'download_symbols': 'ondemand', 11:00:52 INFO - 'enable_stylo': False, 11:00:52 INFO - 'enable_webrender': False, 11:00:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:00:52 INFO - 'tooltool.py': '/tools/tooltool.py', 11:00:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:00:52 INFO - '/tools/misc-python/virtualenv.py')}, 11:00:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:00:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:00:52 INFO - 'gecko_profile': False, 11:00:52 INFO - 'gecko_profile_interval': 0, 11:00:52 INFO - 'installer_path': 'installer.exe', 11:00:52 INFO - 'log_level': 'info', 11:00:52 INFO - 'log_name': 'talos', 11:00:52 INFO - 'log_to_console': True, 11:00:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:00:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:00:52 INFO - 'opt_config_files': (), 11:00:52 INFO - 'pip_index': False, 11:00:52 INFO - 'suite': 'dromaeojs-e10s', 11:00:52 INFO - 'system_bits': '32', 11:00:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:00:52 INFO - 'title': 'talos-linux64-ix-086', 11:00:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:00:52 INFO - 'use_talos_json': True, 11:00:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:00:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:00:52 INFO - 'work_dir': 'build'} 11:00:52 INFO - [mozharness: 2017-08-16 18:00:52.528439Z] Running clobber step. 11:00:52 INFO - Running pre-action listener: _resource_record_pre_action 11:00:52 INFO - Running main action method: clobber 11:00:52 INFO - rmtree: /builds/slave/test/build 11:00:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:00:53 INFO - Running post-action listener: _resource_record_post_action 11:00:53 INFO - [mozharness: 2017-08-16 18:00:53.722439Z] Finished clobber step (success) 11:00:53 INFO - [mozharness: 2017-08-16 18:00:53.722540Z] Running read-buildbot-config step. 11:00:53 INFO - Running pre-action listener: _resource_record_pre_action 11:00:53 INFO - Running main action method: read_buildbot_config 11:00:53 INFO - Using buildbot properties: 11:00:53 INFO - { 11:00:53 INFO - "project": "", 11:00:53 INFO - "product": "firefox", 11:00:53 INFO - "who": "ffxbld@noreply.mozilla.org", 11:00:53 INFO - "installer_path": "public/build/target.tar.bz2", 11:00:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:00:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:00:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:00:53 INFO - "stage_platform": "linux64", 11:00:53 INFO - "basedir": "/builds/slave/test", 11:00:53 INFO - "buildnumber": 772, 11:00:53 INFO - "platform": "ubuntu64_hw", 11:00:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:00:53 INFO - "slavebuilddir": "test", 11:00:53 INFO - "taskId": "FjAPci2LRYCwJAa84taHXA", 11:00:53 INFO - "branch": "mozilla-central", 11:00:53 INFO - "script_repo_revision": "production", 11:00:53 INFO - "revision": "07ce8c96222d533fc89c02802143d35d7c351f9c", 11:00:53 INFO - "slavename": "talos-linux64-ix-086", 11:00:53 INFO - "repo_path": "mozilla-central" 11:00:53 INFO - } 11:00:53 INFO - Finding installer, test and symbols from parent task. 11:00:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FjAPci2LRYCwJAa84taHXA'}, attempt #1 11:00:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FjAPci2LRYCwJAa84taHXA'}, attempt #1 11:00:54 INFO - Task dependencies: YbqntiLNQ_uKrat-Wa379w 11:00:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w'}, attempt #1 11:00:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 11:00:55 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.test_packages.json 11:00:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.crashreporter-symbols.zip 11:00:55 INFO - Running post-action listener: _resource_record_post_action 11:00:55 INFO - [mozharness: 2017-08-16 18:00:55.064810Z] Finished read-buildbot-config step (success) 11:00:55 INFO - [mozharness: 2017-08-16 18:00:55.065032Z] Running download-and-extract step. 11:00:55 INFO - Running pre-action listener: _resource_record_pre_action 11:00:55 INFO - Running main action method: download_and_extract 11:00:55 INFO - mkdir: /builds/slave/test/build/tests 11:00:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:00:55 INFO - trying https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.test_packages.json 11:00:55 INFO - Downloading https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:00:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:00:55 INFO - Downloaded 989 bytes. 11:00:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:00:55 INFO - Using the following test package requirements: 11:00:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:00:55 INFO - u'common': [u'target.common.tests.zip'], 11:00:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:00:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:00:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:00:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:00:55 INFO - u'mozbase': [u'target.common.tests.zip'], 11:00:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:00:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:00:55 INFO - u'web-platform': [u'target.common.tests.zip', 11:00:55 INFO - u'target.web-platform.tests.tar.gz'], 11:00:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:00:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:00:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.common.tests.zip 11:00:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:00:55 INFO - Fetch https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.common.tests.zip into memory 11:00:56 INFO - Content-Length response header: 54003849 11:00:56 INFO - Bytes received: 54003849 11:01:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:01:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.common.tests.zip 11:01:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:01:08 INFO - Fetch https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.common.tests.zip into memory 11:01:09 INFO - Content-Length response header: 54003849 11:01:09 INFO - Bytes received: 54003849 11:01:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.talos.tests.zip 11:01:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:01:22 INFO - Fetch https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.talos.tests.zip into memory 11:01:23 INFO - Content-Length response header: 14496050 11:01:23 INFO - Bytes received: 14496050 11:01:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:23 INFO - trying https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 11:01:23 INFO - Downloading https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:01:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:01:26 INFO - Downloaded 61902147 bytes. 11:01:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 11:01:26 INFO - mkdir: /builds/slave/test/properties 11:01:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:01:26 INFO - Writing to file /builds/slave/test/properties/build_url 11:01:26 INFO - Contents: 11:01:26 INFO - build_url:https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 11:01:26 INFO - Running post-action listener: _resource_record_post_action 11:01:26 INFO - Running post-action listener: set_extra_try_arguments 11:01:26 INFO - [mozharness: 2017-08-16 18:01:26.431314Z] Finished download-and-extract step (success) 11:01:26 INFO - [mozharness: 2017-08-16 18:01:26.431413Z] Running populate-webroot step. 11:01:26 INFO - Running pre-action listener: _resource_record_pre_action 11:01:26 INFO - Running main action method: populate_webroot 11:01:26 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:01:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:01:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:01:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:01:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:01:26 INFO - 'tests': ['damp', 'tps']}, 11:01:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:01:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:01:26 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:01:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:01:26 INFO - 'other-e10s': {'tests': ['a11yr', 11:01:26 INFO - 'ts_paint', 11:01:26 INFO - 'tpaint', 11:01:26 INFO - 'sessionrestore', 11:01:26 INFO - 'sessionrestore_many_windows', 11:01:26 INFO - 'sessionrestore_no_auto_restore', 11:01:26 INFO - 'tabpaint']}, 11:01:26 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:01:26 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:01:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:01:26 INFO - 'tsvgr_opacity', 11:01:26 INFO - 'tart', 11:01:26 INFO - 'tscrollx', 11:01:26 INFO - 'tsvg_static']}, 11:01:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:01:26 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:01:26 INFO - 'talos_options': ['--mitmproxy', 11:01:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:01:26 INFO - '--firstNonBlankPaint'], 11:01:26 INFO - 'tests': ['tp6_google', 11:01:26 INFO - 'tp6_youtube', 11:01:26 INFO - 'tp6_amazon', 11:01:26 INFO - 'tp6_facebook']}, 11:01:26 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:01:26 INFO - 'talos_options': ['--stylo', 11:01:26 INFO - '--mitmproxy', 11:01:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:01:26 INFO - '--firstNonBlankPaint'], 11:01:26 INFO - 'tests': ['tp6_google', 11:01:26 INFO - 'tp6_youtube', 11:01:26 INFO - 'tp6_amazon', 11:01:26 INFO - 'tp6_facebook']}, 11:01:26 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:01:26 INFO - 'talos_options': ['--stylo', 11:01:26 INFO - '--stylo-threads=1', 11:01:26 INFO - '--mitmproxy', 11:01:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:01:26 INFO - '--firstNonBlankPaint'], 11:01:26 INFO - 'tests': ['tp6_google', 11:01:26 INFO - 'tp6_youtube', 11:01:26 INFO - 'tp6_amazon', 11:01:26 INFO - 'tp6_facebook']}, 11:01:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:01:26 INFO - 'talos_options': ['--xperf_path', 11:01:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:01:26 INFO - 'tests': ['tp5n']}}} 11:01:26 INFO - Running post-action listener: _resource_record_post_action 11:01:26 INFO - [mozharness: 2017-08-16 18:01:26.440828Z] Finished populate-webroot step (success) 11:01:26 INFO - [mozharness: 2017-08-16 18:01:26.440929Z] Running create-virtualenv step. 11:01:26 INFO - Running pre-action listener: _resource_record_pre_action 11:01:26 INFO - Running main action method: create_virtualenv 11:01:26 INFO - Creating virtualenv /builds/slave/test/build/venv 11:01:26 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 11:01:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:01:26 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:01:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:01:27 INFO - Using real prefix '/usr' 11:01:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:01:28 INFO - Installing distribute.............................................................................................................................................................................................done. 11:01:30 INFO - Installing pip.................done. 11:01:30 INFO - Return code: 0 11:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:01:30 INFO - Reading from file tmpfile_stdout 11:01:30 INFO - Output received: 11:01:30 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) 11:01:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:30 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 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:01:30 INFO - Using env: {'DISPLAY': ':0', 11:01:30 INFO - 'HOME': '/home/cltbld', 11:01:30 INFO - 'LANG': 'en_US.UTF-8', 11:01:30 INFO - 'LANGUAGE': 'en_US:en', 11:01:30 INFO - 'LOGNAME': 'cltbld', 11:01:30 INFO - 'MAIL': '/var/mail/cltbld', 11:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:30 INFO - 'MOZ_NO_REMOTE': '1', 11:01:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:30 INFO - 'NO_EM_RESTART': '1', 11:01:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:30 INFO - 'PWD': '/builds/slave/test', 11:01:30 INFO - 'SHELL': '/bin/bash', 11:01:30 INFO - 'SHLVL': '1', 11:01:30 INFO - 'TERM': 'linux', 11:01:30 INFO - 'TMOUT': '86400', 11:01:30 INFO - 'USER': 'cltbld', 11:01:30 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:30 INFO - '_': '/tools/buildbot/bin/python'} 11:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:30 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 11:01:30 INFO - Cleaning up... 11:01:30 INFO - Return code: 0 11:01:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:30 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 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:01:30 INFO - Using env: {'DISPLAY': ':0', 11:01:30 INFO - 'HOME': '/home/cltbld', 11:01:30 INFO - 'LANG': 'en_US.UTF-8', 11:01:30 INFO - 'LANGUAGE': 'en_US:en', 11:01:30 INFO - 'LOGNAME': 'cltbld', 11:01:30 INFO - 'MAIL': '/var/mail/cltbld', 11:01:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:30 INFO - 'MOZ_NO_REMOTE': '1', 11:01:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:30 INFO - 'NO_EM_RESTART': '1', 11:01:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:30 INFO - 'PWD': '/builds/slave/test', 11:01:30 INFO - 'SHELL': '/bin/bash', 11:01:30 INFO - 'SHLVL': '1', 11:01:30 INFO - 'TERM': 'linux', 11:01:30 INFO - 'TMOUT': '86400', 11:01:30 INFO - 'USER': 'cltbld', 11:01:30 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:30 INFO - '_': '/tools/buildbot/bin/python'} 11:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:31 INFO - Downloading/unpacking psutil>=3.1.1 11:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:01:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:01:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:01:32 INFO - Installing collected packages: psutil 11:01:32 INFO - Running setup.py install for psutil 11:01:32 INFO - building 'psutil._psutil_linux' extension 11:01:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:01:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:01:33 INFO - building 'psutil._psutil_posix' extension 11:01:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:01:33 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:01:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:01:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:01:33 INFO - Successfully installed psutil 11:01:33 INFO - Cleaning up... 11:01:33 INFO - Return code: 0 11:01:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:33 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 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:01:33 INFO - Using env: {'DISPLAY': ':0', 11:01:33 INFO - 'HOME': '/home/cltbld', 11:01:33 INFO - 'LANG': 'en_US.UTF-8', 11:01:33 INFO - 'LANGUAGE': 'en_US:en', 11:01:33 INFO - 'LOGNAME': 'cltbld', 11:01:33 INFO - 'MAIL': '/var/mail/cltbld', 11:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:33 INFO - 'MOZ_NO_REMOTE': '1', 11:01:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:33 INFO - 'NO_EM_RESTART': '1', 11:01:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:33 INFO - 'PWD': '/builds/slave/test', 11:01:33 INFO - 'SHELL': '/bin/bash', 11:01:33 INFO - 'SHLVL': '1', 11:01:33 INFO - 'TERM': 'linux', 11:01:33 INFO - 'TMOUT': '86400', 11:01:33 INFO - 'USER': 'cltbld', 11:01:33 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:33 INFO - '_': '/tools/buildbot/bin/python'} 11:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:01:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:01:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:01:35 INFO - Installing collected packages: mozsystemmonitor 11:01:35 INFO - Running setup.py install for mozsystemmonitor 11:01:35 INFO - Successfully installed mozsystemmonitor 11:01:35 INFO - Cleaning up... 11:01:35 INFO - Return code: 0 11:01:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:01:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:01:35 INFO - Using env: {'DISPLAY': ':0', 11:01:35 INFO - 'HOME': '/home/cltbld', 11:01:35 INFO - 'LANG': 'en_US.UTF-8', 11:01:35 INFO - 'LANGUAGE': 'en_US:en', 11:01:35 INFO - 'LOGNAME': 'cltbld', 11:01:35 INFO - 'MAIL': '/var/mail/cltbld', 11:01:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:35 INFO - 'MOZ_NO_REMOTE': '1', 11:01:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:35 INFO - 'NO_EM_RESTART': '1', 11:01:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:35 INFO - 'PWD': '/builds/slave/test', 11:01:35 INFO - 'SHELL': '/bin/bash', 11:01:35 INFO - 'SHLVL': '1', 11:01:35 INFO - 'TERM': 'linux', 11:01:35 INFO - 'TMOUT': '86400', 11:01:35 INFO - 'USER': 'cltbld', 11:01:35 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:35 INFO - '_': '/tools/buildbot/bin/python'} 11:01:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:35 INFO - Downloading/unpacking jsonschema==2.5.1 11:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:01:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:36 INFO - Downloading functools32-3.2.3-2.tar.gz 11:01:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:01:36 INFO - warning: no files found matching '*.txt' 11:01:36 INFO - no previously-included directories found matching 'build' 11:01:36 INFO - no previously-included directories found matching 'dist' 11:01:36 INFO - no previously-included directories found matching '.git*' 11:01:36 INFO - Installing collected packages: jsonschema, functools32 11:01:36 INFO - Running setup.py install for functools32 11:01:36 INFO - warning: no files found matching '*.txt' 11:01:36 INFO - no previously-included directories found matching 'build' 11:01:36 INFO - no previously-included directories found matching 'dist' 11:01:36 INFO - no previously-included directories found matching '.git*' 11:01:36 INFO - Successfully installed jsonschema functools32 11:01:36 INFO - Cleaning up... 11:01:37 INFO - Return code: 0 11:01:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:37 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 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:01:37 INFO - Using env: {'DISPLAY': ':0', 11:01:37 INFO - 'HOME': '/home/cltbld', 11:01:37 INFO - 'LANG': 'en_US.UTF-8', 11:01:37 INFO - 'LANGUAGE': 'en_US:en', 11:01:37 INFO - 'LOGNAME': 'cltbld', 11:01:37 INFO - 'MAIL': '/var/mail/cltbld', 11:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:37 INFO - 'MOZ_NO_REMOTE': '1', 11:01:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:37 INFO - 'NO_EM_RESTART': '1', 11:01:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:37 INFO - 'PWD': '/builds/slave/test', 11:01:37 INFO - 'SHELL': '/bin/bash', 11:01:37 INFO - 'SHLVL': '1', 11:01:37 INFO - 'TERM': 'linux', 11:01:37 INFO - 'TMOUT': '86400', 11:01:37 INFO - 'USER': 'cltbld', 11:01:37 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:37 INFO - '_': '/tools/buildbot/bin/python'} 11:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:01:37 INFO - Cleaning up... 11:01:37 INFO - Return code: 0 11:01:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:37 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 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:01:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:01:37 INFO - Using env: {'DISPLAY': ':0', 11:01:37 INFO - 'HOME': '/home/cltbld', 11:01:37 INFO - 'LANG': 'en_US.UTF-8', 11:01:37 INFO - 'LANGUAGE': 'en_US:en', 11:01:37 INFO - 'LOGNAME': 'cltbld', 11:01:37 INFO - 'MAIL': '/var/mail/cltbld', 11:01:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:37 INFO - 'MOZ_NO_REMOTE': '1', 11:01:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:37 INFO - 'NO_EM_RESTART': '1', 11:01:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:37 INFO - 'PWD': '/builds/slave/test', 11:01:37 INFO - 'SHELL': '/bin/bash', 11:01:37 INFO - 'SHLVL': '1', 11:01:37 INFO - 'TERM': 'linux', 11:01:37 INFO - 'TMOUT': '86400', 11:01:37 INFO - 'USER': 'cltbld', 11:01:37 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:37 INFO - '_': '/tools/buildbot/bin/python'} 11:01:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:37 INFO - Downloading/unpacking blobuploader==1.2.4 11:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:38 INFO - Downloading blobuploader-1.2.4.tar.gz 11:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:01:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:01:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:39 INFO - Downloading docopt-0.6.1.tar.gz 11:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:01:39 INFO - Installing collected packages: blobuploader, requests, docopt 11:01:39 INFO - Running setup.py install for blobuploader 11:01:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:01:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:01:39 INFO - Running setup.py install for requests 11:01:39 INFO - Running setup.py install for docopt 11:01:39 INFO - Successfully installed blobuploader requests docopt 11:01:39 INFO - Cleaning up... 11:01:39 INFO - Return code: 0 11:01:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:39 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 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:01:39 INFO - Using env: {'DISPLAY': ':0', 11:01:39 INFO - 'HOME': '/home/cltbld', 11:01:39 INFO - 'LANG': 'en_US.UTF-8', 11:01:39 INFO - 'LANGUAGE': 'en_US:en', 11:01:39 INFO - 'LOGNAME': 'cltbld', 11:01:39 INFO - 'MAIL': '/var/mail/cltbld', 11:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:39 INFO - 'MOZ_NO_REMOTE': '1', 11:01:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:39 INFO - 'NO_EM_RESTART': '1', 11:01:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:39 INFO - 'PWD': '/builds/slave/test', 11:01:39 INFO - 'SHELL': '/bin/bash', 11:01:39 INFO - 'SHLVL': '1', 11:01:39 INFO - 'TERM': 'linux', 11:01:39 INFO - 'TMOUT': '86400', 11:01:39 INFO - 'USER': 'cltbld', 11:01:39 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:39 INFO - '_': '/tools/buildbot/bin/python'} 11:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:01:39 INFO - Running setup.py (path:/tmp/pip-9nMiDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:01:40 INFO - Running setup.py (path:/tmp/pip-H_aJu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:01:40 INFO - Running setup.py (path:/tmp/pip-Ai_MJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:01:40 INFO - Running setup.py (path:/tmp/pip-PMSYDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:01:40 INFO - Running setup.py (path:/tmp/pip-GwOfgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:01:40 INFO - Running setup.py (path:/tmp/pip-G8eTXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:01:40 INFO - Running setup.py (path:/tmp/pip-2WbXl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:01:40 INFO - Running setup.py (path:/tmp/pip-xWyXBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:01:40 INFO - Running setup.py (path:/tmp/pip-YQ7AYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:01:40 INFO - Running setup.py (path:/tmp/pip-BIwr0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:01:40 INFO - Running setup.py (path:/tmp/pip-89BTCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:01:40 INFO - Running setup.py (path:/tmp/pip-yHdAli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:01:40 INFO - Running setup.py (path:/tmp/pip-QAeYT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:01:40 INFO - Running setup.py (path:/tmp/pip-nS0aKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:01:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:01:40 INFO - Running setup.py (path:/tmp/pip-rA6k8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:01:41 INFO - Running setup.py (path:/tmp/pip-EPc9gZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:01:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:01:41 INFO - Running setup.py (path:/tmp/pip-hqqmKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:01:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:01:41 INFO - Running setup.py install for manifestparser 11:01:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:01:41 INFO - Running setup.py install for mozcrash 11:01:41 INFO - Running setup.py install for mozdebug 11:01:41 INFO - Running setup.py install for mozdevice 11:01:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:01:41 INFO - Running setup.py install for mozfile 11:01:41 INFO - Running setup.py install for mozhttpd 11:01:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:01:41 INFO - Running setup.py install for mozinfo 11:01:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:01:41 INFO - Running setup.py install for mozInstall 11:01:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:01:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:01:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:01:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:01:41 INFO - Running setup.py install for mozleak 11:01:42 INFO - Running setup.py install for mozlog 11:01:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:01:42 INFO - Running setup.py install for moznetwork 11:01:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:01:42 INFO - Running setup.py install for mozprocess 11:01:42 INFO - Running setup.py install for mozprofile 11:01:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:01:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:01:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:01:42 INFO - Running setup.py install for mozrunner 11:01:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:01:42 INFO - Running setup.py install for mozscreenshot 11:01:42 INFO - Running setup.py install for moztest 11:01:42 INFO - Running setup.py install for mozversion 11:01:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:01:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:01:42 INFO - Cleaning up... 11:01:42 INFO - Return code: 0 11:01:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:42 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 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:01:42 INFO - Using env: {'DISPLAY': ':0', 11:01:42 INFO - 'HOME': '/home/cltbld', 11:01:42 INFO - 'LANG': 'en_US.UTF-8', 11:01:42 INFO - 'LANGUAGE': 'en_US:en', 11:01:42 INFO - 'LOGNAME': 'cltbld', 11:01:42 INFO - 'MAIL': '/var/mail/cltbld', 11:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:42 INFO - 'MOZ_NO_REMOTE': '1', 11:01:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:42 INFO - 'NO_EM_RESTART': '1', 11:01:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:42 INFO - 'PWD': '/builds/slave/test', 11:01:42 INFO - 'SHELL': '/bin/bash', 11:01:42 INFO - 'SHLVL': '1', 11:01:42 INFO - 'TERM': 'linux', 11:01:42 INFO - 'TMOUT': '86400', 11:01:42 INFO - 'USER': 'cltbld', 11:01:42 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:42 INFO - '_': '/tools/buildbot/bin/python'} 11:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:01:43 INFO - Running setup.py (path:/tmp/pip-7AmJO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:01:43 INFO - Running setup.py (path:/tmp/pip-b_ML1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:01:43 INFO - Running setup.py (path:/tmp/pip-q9e8Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:01:43 INFO - Running setup.py (path:/tmp/pip-eKBbRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:01:43 INFO - Running setup.py (path:/tmp/pip-6FLgd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:01:43 INFO - Running setup.py (path:/tmp/pip-IwNsvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:01:43 INFO - Running setup.py (path:/tmp/pip-hR8XvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:01:43 INFO - Running setup.py (path:/tmp/pip-3YrmSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:01:43 INFO - Running setup.py (path:/tmp/pip-8Q5q0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:01:43 INFO - Running setup.py (path:/tmp/pip-P4KeUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:01:43 INFO - Running setup.py (path:/tmp/pip-x7EeU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:01:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:01:43 INFO - Running setup.py (path:/tmp/pip-BW0QUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:01:44 INFO - Running setup.py (path:/tmp/pip-TCKWCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:01:44 INFO - Running setup.py (path:/tmp/pip-j4XbTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:01:44 INFO - Running setup.py (path:/tmp/pip-GnFk6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:01:44 INFO - Running setup.py (path:/tmp/pip-ZkPKxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:01:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:01:44 INFO - Running setup.py (path:/tmp/pip-4pSiVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:01:44 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:01:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:45 INFO - Downloading blessings-1.6.tar.gz 11:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:01:45 INFO - Installing collected packages: blessings 11:01:45 INFO - Running setup.py install for blessings 11:01:45 INFO - Successfully installed blessings 11:01:45 INFO - Cleaning up... 11:01:45 INFO - Return code: 0 11:01:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:01:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:01:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:01:45 INFO - Reading from file tmpfile_stdout 11:01:45 INFO - Current package versions: 11:01:45 INFO - argparse == 1.2.1 11:01:45 INFO - blessings == 1.6 11:01:45 INFO - blobuploader == 1.2.4 11:01:45 INFO - docopt == 0.6.1 11:01:45 INFO - functools32 == 3.2.3-2 11:01:45 INFO - jsonschema == 2.5.1 11:01:45 INFO - manifestparser == 1.1 11:01:45 INFO - mozInstall == 1.13 11:01:45 INFO - mozcrash == 1.0 11:01:45 INFO - mozdebug == 0.1 11:01:45 INFO - mozdevice == 0.51 11:01:45 INFO - mozfile == 1.2 11:01:45 INFO - mozhttpd == 0.7 11:01:45 INFO - mozinfo == 0.10 11:01:45 INFO - mozleak == 0.1 11:01:45 INFO - mozlog == 3.5 11:01:45 INFO - moznetwork == 0.27 11:01:45 INFO - mozprocess == 0.25 11:01:45 INFO - mozprofile == 0.28 11:01:45 INFO - mozrunner == 6.13 11:01:45 INFO - mozscreenshot == 0.1 11:01:45 INFO - mozsystemmonitor == 0.3 11:01:45 INFO - moztest == 0.8 11:01:45 INFO - mozversion == 1.4 11:01:45 INFO - psutil == 3.1.1 11:01:45 INFO - requests == 1.2.3 11:01:46 INFO - wsgiref == 0.1.2 11:01:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:46 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 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:01:46 INFO - Using env: {'DISPLAY': ':0', 11:01:46 INFO - 'HOME': '/home/cltbld', 11:01:46 INFO - 'LANG': 'en_US.UTF-8', 11:01:46 INFO - 'LANGUAGE': 'en_US:en', 11:01:46 INFO - 'LOGNAME': 'cltbld', 11:01:46 INFO - 'MAIL': '/var/mail/cltbld', 11:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:46 INFO - 'MOZ_NO_REMOTE': '1', 11:01:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:46 INFO - 'NO_EM_RESTART': '1', 11:01:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:46 INFO - 'PWD': '/builds/slave/test', 11:01:46 INFO - 'SHELL': '/bin/bash', 11:01:46 INFO - 'SHLVL': '1', 11:01:46 INFO - 'TERM': 'linux', 11:01:46 INFO - 'TMOUT': '86400', 11:01:46 INFO - 'USER': 'cltbld', 11:01:46 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:46 INFO - '_': '/tools/buildbot/bin/python'} 11:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:01:46 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:01:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:01:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:01:47 INFO - Installing collected packages: simplejson 11:01:47 INFO - Running setup.py install for simplejson 11:01:47 INFO - building 'simplejson._speedups' extension 11:01:47 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:01:48 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:01:48 INFO - Successfully installed simplejson 11:01:48 INFO - Cleaning up... 11:01:48 INFO - Return code: 0 11:01:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:01: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')]} 11:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:01: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')]} 11:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:01:48 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 0x10b41f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11baa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x135def0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, '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': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:01:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:01:48 INFO - Using env: {'DISPLAY': ':0', 11:01:48 INFO - 'HOME': '/home/cltbld', 11:01:48 INFO - 'LANG': 'en_US.UTF-8', 11:01:48 INFO - 'LANGUAGE': 'en_US:en', 11:01:48 INFO - 'LOGNAME': 'cltbld', 11:01:48 INFO - 'MAIL': '/var/mail/cltbld', 11:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:01:48 INFO - 'MOZ_NO_REMOTE': '1', 11:01:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:01:48 INFO - 'NO_EM_RESTART': '1', 11:01:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:01:48 INFO - 'PWD': '/builds/slave/test', 11:01:48 INFO - 'SHELL': '/bin/bash', 11:01:48 INFO - 'SHLVL': '1', 11:01:48 INFO - 'TERM': 'linux', 11:01:48 INFO - 'TMOUT': '86400', 11:01:48 INFO - 'USER': 'cltbld', 11:01:48 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:01:48 INFO - '_': '/tools/buildbot/bin/python'} 11:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:01:48 INFO - Cleaning up... 11:01:48 INFO - Return code: 0 11:01:48 INFO - Running post-action listener: _resource_record_post_action 11:01:48 INFO - Running post-action listener: _start_resource_monitoring 11:01:48 INFO - Starting resource monitoring. 11:01:48 INFO - [mozharness: 2017-08-16 18:01:48.745018Z] Finished create-virtualenv step (success) 11:01:48 INFO - [mozharness: 2017-08-16 18:01:48.746098Z] Running install step. 11:01:48 INFO - Running pre-action listener: _resource_record_pre_action 11:01:48 INFO - Running main action method: install 11:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:01:48 INFO - Reading from file tmpfile_stdout 11:01:48 INFO - Detecting whether we're running mozinstall >=1.0... 11:01:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:01:49 INFO - Reading from file tmpfile_stdout 11:01:49 INFO - Output received: 11:01:49 INFO - Usage: mozinstall [options] installer 11:01:49 INFO - Options: 11:01:49 INFO - -h, --help show this help message and exit 11:01:49 INFO - -d DEST, --destination=DEST 11:01:49 INFO - Directory to install application into. [default: 11:01:49 INFO - "/builds/slave/test"] 11:01:49 INFO - --app=APP Application being installed. [default: firefox] 11:01:49 INFO - mkdir: /builds/slave/test/build/application 11:01:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:02:07 INFO - Reading from file tmpfile_stdout 11:02:07 INFO - Output received: 11:02:07 INFO - /builds/slave/test/build/application/firefox/firefox 11:02:07 INFO - Running post-action listener: _resource_record_post_action 11:02:07 INFO - [mozharness: 2017-08-16 18:02:07.046337Z] Finished install step (success) 11:02:07 INFO - [mozharness: 2017-08-16 18:02:07.046617Z] Running setup-mitmproxy step. 11:02:07 INFO - Running pre-action listener: _resource_record_pre_action 11:02:07 INFO - Running main action method: setup_mitmproxy 11:02:07 INFO - Skipping: mitmproxy is not required 11:02:07 INFO - Running post-action listener: _resource_record_post_action 11:02:07 INFO - [mozharness: 2017-08-16 18:02:07.047722Z] Finished setup-mitmproxy step (success) 11:02:07 INFO - [mozharness: 2017-08-16 18:02:07.047948Z] Running run-tests step. 11:02:07 INFO - Running pre-action listener: _resource_record_pre_action 11:02:07 INFO - Running pre-action listener: _set_gcov_prefix 11:02:07 INFO - Running main action method: run_tests 11:02:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:02:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:02:07 INFO - Python 2.7.3 11:02:07 INFO - Return code: 0 11:02:07 INFO - grabbing minidump binary from tooltool 11:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:07 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 0x135b0b0>, '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 0x135bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x135c440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:02:07 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 11:02:07 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 11:02:07 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 11:02:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:02:07 INFO - Return code: 0 11:02:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:02:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:02:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:02:07 INFO - ENV: RUST_BACKTRACE is now full 11:02:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:02:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:02:07 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/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:02:07 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/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-086 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:02:07 INFO - Using env: {'DISPLAY': ':0', 11:02:07 INFO - 'HOME': '/home/cltbld', 11:02:07 INFO - 'LANG': 'en_US.UTF-8', 11:02:07 INFO - 'LANGUAGE': 'en_US:en', 11:02:07 INFO - 'LOGNAME': 'cltbld', 11:02:07 INFO - 'MAIL': '/var/mail/cltbld', 11:02:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:02:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:02:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:07 INFO - 'MOZ_NO_REMOTE': '1', 11:02:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:02:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:02:07 INFO - 'NO_EM_RESTART': '1', 11:02:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:02:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:07 INFO - 'PWD': '/builds/slave/test', 11:02:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:02:07 INFO - 'RUST_BACKTRACE': 'full', 11:02:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:02:07 INFO - 'SHELL': '/bin/bash', 11:02:07 INFO - 'SHLVL': '1', 11:02:07 INFO - 'TERM': 'linux', 11:02:07 INFO - 'TMOUT': '86400', 11:02:07 INFO - 'USER': 'cltbld', 11:02:07 INFO - 'XDG_SESSION_COOKIE': '438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245', 11:02:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:07 INFO - '_': '/tools/buildbot/bin/python'} 11:02:07 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/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-086', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:02:07 INFO - mozversion application_buildid: 20170816171050 11:02:07 INFO - mozversion application_changeset: 07ce8c96222d533fc89c02802143d35d7c351f9c 11:02:07 INFO - mozversion application_display_name: Nightly 11:02:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:02:07 INFO - mozversion application_name: Firefox 11:02:07 INFO - mozversion application_remotingname: firefox 11:02:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:02:07 INFO - mozversion application_vendor: Mozilla 11:02:07 INFO - mozversion application_version: 57.0a1 11:02:07 INFO - mozversion platform_buildid: 20170816171050 11:02:07 INFO - mozversion platform_changeset: 07ce8c96222d533fc89c02802143d35d7c351f9c 11:02:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:02:07 INFO - mozversion platform_version: 57.0a1 11:02:07 INFO - using testdate: 1502906527 11:02:07 INFO - actual date: 1502906527 11:02:07 INFO - starting webserver on 'localhost:49071' 11:02:07 INFO - SUITE-START | Running 2 tests 11:02:07 INFO - TEST-START | dromaeo_css 11:02:07 INFO - Initialising browser for dromaeo_css test... 11:02:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49071/getInfo.html -profile /tmp/tmp_AtmxZ/profile 11:02:07 INFO - TEST-INFO | started process 3303 (/builds/slave/test/build/application/firefox/firefox http://localhost:49071/getInfo.html) 11:02:15 INFO - TEST-INFO | 3303: exit 0 11:02:15 INFO - Browser initialized. 11:02:15 INFO - Running cycle 1/1 for dromaeo_css test... 11:02:15 INFO - TEST-INFO | started process 3516 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_AtmxZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:02:16 INFO - PID 3516 | 11:02:16 INFO - PID 3516 | (/builds/slave/test/build/application/firefox/firefox:3564): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:02:16 INFO - PID 3516 | 11:02:26 INFO - PID 3516 | RSS: Main: 169455616 11:02:26 INFO - PID 3516 | 11:05:25 INFO - PID 3516 | Cycle 1(1): loaded http://localhost:49071/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49071/tests/dromaeo/cssquery-ext.html) 11:05:26 INFO - PID 3516 | RSS: Main: 173486080 11:05:26 INFO - PID 3516 | 11:08:28 INFO - PID 3516 | Cycle 1(1): loaded http://localhost:49071/tests/dromaeo/cssquery-ext.html (next: http://localhost:49071/tests/dromaeo/cssquery-jquery.html) 11:08:29 INFO - PID 3516 | RSS: Main: 170754048 11:08:29 INFO - PID 3516 | 11:11:30 INFO - PID 3516 | Cycle 1(1): loaded http://localhost:49071/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49071/tests/dromaeo/cssquery-mootools.html) 11:11:31 INFO - PID 3516 | RSS: Main: 173760512 11:11:31 INFO - PID 3516 | 11:14:33 INFO - PID 3516 | Cycle 1(1): loaded http://localhost:49071/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49071/tests/dromaeo/cssquery-prototype.html) 11:14:33 INFO - PID 3516 | RSS: Main: 171261952 11:14:33 INFO - PID 3516 | 11:17:37 INFO - PID 3516 | Cycle 1(1): loaded http://localhost:49071/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49071/tests/dromaeo/cssquery-yui.html) 11:17:37 INFO - PID 3516 | RSS: Main: 174706688 11:17:37 INFO - PID 3516 | 11:20:39 INFO - PID 3516 | Cycle 1(1): loaded http://localhost:49071/tests/dromaeo/cssquery-yui.html (next: http://localhost:49071/tests/dromaeo/cssquery-dojo.html) 11:20:39 INFO - PID 3516 | RSS: Main: 175276032 11:20:39 INFO - PID 3516 | 11:20:39 INFO - PID 3516 | __start_tp_report 11:20:39 INFO - PID 3516 | _x_x_mozilla_page_load 11:20:39 INFO - PID 3516 | _x_x_mozilla_page_load_details 11:20:39 INFO - PID 3516 | |i|pagename|runs| 11:20:39 INFO - PID 3516 | |0;dojo.html;3159.1786135604743;3172.2545201877556;3179.0621766578856;3206.567113439685;3212.164837142344;6175;6209.534284928632;6232.968835155823;6240.43836054755;6274.968625156879;5915.349311575723;6008.939910600892;6009.06859436788;6023.789167379143;6029.698515074239;5554.888902221953;5567.66594004361;5585.273914391123;5588.6646801191955;5602.887942241153;5617.719114044302;5622.128570071645;5622.803201887935;5648.350439699426;5648.576356773238;5687;5707.971460142693;5718.285214348207;5726.599138060337;5812.331581868076;5666.971665141689;5742.310922689262;5748.281464816877;5748.281464816898;5751.482366586985;5446.346438427375;5492.725363731837;5553.944460555424;5567.053600887859;5654.6041777075625;5336.146216605324;5910.940890591125;5984.700764961778;6048.243969503812;6150.446459818638;6574.8356291093105;6582.868342633168;6586.7035983380865;6651.301613330627;6654.10169627099;6487.253965793947;6557.93442065578;6579.078928949951;6652.800415987528;6745.1905771307265;6111.2666480022235;6124.326324104345;6129;6179.84550386244;6261.405166509175;6796.898046529306;6815.454763618898;6819.181698196248;6820.727170913207;6823.283555226729;6335.176427064544;6441.0982462455295;6496.545241833121;6520.445762110179;6531.987541931008;6559.508036897157;6584.078229047937;6638.468922486286;6656.833579160463;6663.06717059612;6380.266269378988;6491.383318584727;6492.0261960706275;6499.740010399626;6503.122078519339;6500.284968653445;6555.967220163869;6606.900896486556;6677.799666010027;6678.365555272241;5446.564274858002;5451.182322651554;5454.181872719124;5464.098423760114;5469.480399362054;4561.087782443525;4655.301704744315;4655.976720116378;4677.6257899367965;4684.156851766715;5630.40880707528;5743.1385292206505;5766.452187042224;5834.883302334015;5835.183074369592;6602.570832895846;6618.404343609098;6627.469802415795;6629.535932484776;6630;5643.661380317194;5715.514181294637;5717.399673034354;5717.999350113713;5729.942700572941;5863.208466857005;5941.55438342126;5946.256717910262;5949.613275137102;5951.970240148771;5924.61490003157;6405.775797847053;6495.090687303781;6496.9929660902635;6521.510886683518;6619.735210591715;6651.26836048044;6652.534322597372;6671.532992690465;6685.130932978682;6397.616143031433;6429.58207716497;6516.120323756232;6517.283098859217;6529.869402611826;6483.935160648333;6494.0258961156205;6575.145231119924;6585.176852893388;6592;6473.611583305017;6608.603483790988;6623.701933412912;6624.105745724266;6628.469722422291;6305.432511074026;6384.457321127757;6387.776427825004;6397.104405383157;6398.840028999312;6366.395192456709;6398.872022559616;6469.061986012096;6478.740850366121;6492;6290.0879372491645;6368.172137622079;6427.807165785034;6450.225972883284;6456.192975878015;6372.74509019634;6424.710888010144;6490.7079181436;6500.284968653539;6507.73969041252;6180.598261113013;6267;6317.431431171033;6320.873582528416;6331.873362532631 11:20:39 INFO - PID 3516 | |1;ext.html;794.9165337639548;837.9245867871892;856.790086428825;859.8624220124781;1165.021382039087;40920.59079409205;41272.58727412725;41398.793006035005;41425.79287103564;41465.378019329735;41511.37732934008;41978.37032444516;41998;42005.78997105018;42036.57963420365;7367.18960914299;7498.587577683225;7503.174650788409;7535;7555.962220188919;7366.77899663011;7479.326860582546;7479.3642540384135;7482.51363661363;7488.737894173705;7336.963315183417;7482.6632801524065;7556.244375562427;7563.319301262885;7565.243475652445;7440.888386674177;7449.180590135081;7458.216887226845;7479.3642540384135;7487.588182649952;7247.818804529877;7405.8889116663295;7434.9256507434775;7459.738909138154;7480.364169045583;37100;37236.81381593102;37414;37425.81287093574;37543.81228093842;3483.3381657484997;3538.9469157962653;3553.218291975761;3553.751237413382;3556.8043757593323;30480;30494.69505304963;30527.08418747441;30586.541201881988;30605.234869128228;5023.246513023039;5043.520865517807;5055.165897626887;5057.69653820772;5064.82273120439;191007;194177;194853;195596;195687;48184.7590762051;48313.516864830905;48314;48515;48528.5147148531;50262.748686257066;50270.497295027315;50420;50454.49545504498;50522;15546.533603991898;15574.987625804286;15583.610409739847;15660.608484787972;15666.216689165496;12762.80855787164;12838;12839.48642054326;12842.807357889638;12845.357732113356;12685.809712854314;12841.614751557468;14294.785578216335;14325.212113333866;14343.282835858374;6036.366181550961;6105.8473538161015;6178.5366097542865;6217.077973352517;6285.491482044368;1006.2955930848435;1025.2566889005411;1026.7741096958657;1031.4584842957506;1032.9896701033044;23471.88264058669;24163.275101746975;24168.87915560411;24453.144139955373;24569.508609827702;1102.3385968418884;1116.7989761842946;1117.2570240789946;1119.356370087203;1138.3738943580997;12500.249985000748;12545.560905368267;12589.433475493624;12616.17994830333;12734.04494662904;251009;252901;253222;253897;254594;51121;51579.22631160536;51582.226266606034;51656.74171629118;51783.48216517787;1456.9635759106106;1468.3906178935836;1479.0681870421565;1491.7016596680708;1496.7006598680089;22767.772322276564;23024.4243893904;23043.423914401603;23119.653205201597;23172.652410213523;37160.62839371571;37164.6283537172;37205.25589488141;37210.44184337183;37268.440973385965;209381;209500;209947;209992;210297;35463.46804797982;35499.46750798688;35631.28737425185;35827.283454330245;35849.1037724059;45832.541674582826;46433.30350044819;46491.76754116343;46842;47028.7648561755;33223.501647474826;33597.49603755995;33796;35257.94226173219;35357.46963795597;33329.33341333208;33798.831005845794;33975.49036764401;34054.48918266178;34461.827690861384;36880.44679329758;37022.8148859254;37114.814425927696;37168;37400;33354.166145846546;33471.66528334783;33711.662883371835;34200.31599368049;34205.65794342025;23180.76819231786;25296.494070118126;25556.74443255618;25990.740092598833;26096.73903261019 11:20:39 INFO - PID 3516 | |2;jquery.html;567.452408425869;568.8094488346404;570.3013808085094;583.5011065538964;584.4097461563821;21789.237376691817;22683.77316226837;22957.966891489883;22998.770012299872;23015.309540713806;26612.334691632717;27055;27070;27106.186814395598;27327.316817079532;6491.09124722538;6633.170853643294;6638.070670106189;6638.4689224861895;6639.900401493981;5954.9404505955035;6476.4818814494965;6485.935140648604;6633.805914935309;6690.866182676344;6435.066915297279;6447.323031081739;6448.80653580391;6470.450011749007;6473.77341793035;6428.421442070189;6447.2263328400895;6454.774082907123;6456.35436456353;6457.192850893638;5969;6000.459958603747;6018.518518518508;6038.818835434944;6039.396060393926;29407.2648183795;29920.700792992226;30385.544216836766;30706.692933070608;31263.687363126304;1886.1512319456162;1912.4931893048354;1918.7505624268895;1919.299455698674;1923.6922092465138;19319.51701207467;19924.601507969903;19939;19976;19995;2192.440927563477;2245.550889822026;2251.4821591034156;2315.7221133463922;2455.508898220345;137412.31293843567;140528;143452;143469.99999999895;144264.278678607;14170.787438188436;14556.126632402089;14835.480758173411;14837;14944.775828362583;22985.310440686808;22992.540149196924;23030.884845575663;23037.88481057584;23058.538829223322;10286.228532860065;10436.660268714091;10507.264491485676;10616.840747388795;10648.467576621139;9842.852357214648;9897.901020989842;9947.900520994843;10001.099901008944;10171.745706357253;7577.734779282699;8840.646374145092;9135.168097889675;9188.770280743034;9569.090936361035;2570.910018149356;2576.9742302576733;2579.380948572329;2611.373270415125;2749.9725002750115;917.4357769971552;930.8045310484739;936.3539157980971;946.1531928923713;976.951152442375;19641.017949102486;19908.70136947947;20047.799522004883;20178.59642807165;20354.59290814205;1020.6274709730894;1021.2638514525337;1049.333673117576;1053.7892421515728;1060.1942523681903;10601.681949541526;11093;11217.495212715447;11240.21318507713;11303.378314192634;506804;551884;555893;558506;561621;116197.4190129044;118220.40889795496;119453.40273298578;121664;123591;1203.0285195566935;1271.1483306184878;1306.4447609765812;1318.136620513565;1326.681596416853;20914.895425522776;20915;21082.472938176667;21299.8935005324;21420.357389278346;59141;60798.39201607927;60859.69570152121;60863.69568152131;61733.691331544855;71605.64197178981;83489.58255208685;84129.57935210285;85795;86270.56864715887;21687.674684880054;22273.665895011913;22511.887440562692;22522.21172258963;22537.098516059174;24393.63409548894;24523.50952980966;24602.630960535247;25296.11463598765;25329.74670253274;18608.34870779516;19032.904835475732;19479.707804382662;19532.53505987085;19532.707009394588;15785;15812.446564370191;16339.754903676692;17099.487015389557;17559.297628094733;18696;19489.70765438491;19546.413607591796;19671.70492442643;19712.60574788525;21176.364709058755;21203.68194477053;21294.680579791006;21343.786562134803;21358.359249222547;18980;19044.714329284794;19050.714239286146;19167.233310667976;19251.903740481208 11:20:39 INFO - PID 3516 | |3;mootools.html;1358.8165597644318;1421.2183299185444;1422.9715405691886;1427.5431861804213;1428.3643778518563;13808.585742427731;14445.349959251833;14447.855521444782;14451.205183714892;14467.27663616819;14665.706685866277;14777.556673299792;14833.925830370834;14853.183074930901;14856.405743770236;2615.62073499343;2683.0743393529297;2684.4899469100906;2684.932876678079;2696.7842572594145;2533.961990570143;2717.144099608617;2722.8502432366213;2727.9181624551297;2729.56326987682;2725.441284536665;2740.068376751904;2761.0336382266146;2783.5268004439195;2785.665720113589;2680.2227354067395;2711.1595405424187;2718.361185121495;2722.8774705138217;2732.8770205340807;2593.6757905261843;2602.258356368426;2610.2430295214554;2611.151375802872;2625.5930330798565;14108.435662573485;14297.142171469744;14312.64218394538;14326.283685815772;14331.42674293027;7390.5196162249285;7546.09446866374;7768.262015108556;7787.961060194662;7836.804079897991;4879.4876537963355;4995.100881841304;5105.412877519096;5114.539691427789;5265.420803711589;1802.4953013156337;1884.7361369408293;2245.8203343732625;2283.680284760135;2334.6964894563766;23802.761972380227;24017.639735403984;24099.39751506209;24153.275401737978;24227;6565.310642382577;6702.19573651165;6748.089007983957;6748.257691653914;6901.965490172517;18558;18614.44156675302;18663.906680466782;18680.81319186818;18683.71974420385;7734.1492435832015;7795.064592248967;7803.141654418009;7814.296713295831;7841.5687137208;6781.6609169541325;6954.547954382949;6954.721811127498;7040.471964602675;7066.470014748915;5363.973180134152;5412.5940554458575;5498.697571633598;5536.280283563192;5587.7485513152005;1665.5669525923347;1689.5015970288794;1714.6399256156346;1722.586579220978;1731.0392732033843;838.8867502887155;858.3690987124459;875.0374587953328;886.5921676029099;889.7997950461095;6967.686454109577;6973.163220413583;6995.4053905417595;7020.05229294049;7059.505834591632;709.6026225313738;713.3615414204259;713.7858642407257;723.8045727653504;730.2697302697303;4334.284843000933;4651.13960398009;5179.119549676564;5249.448807875118;5276.551493123127;19491.220351185795;20106.396808095782;20118.094685739175;20125.39623811288;20139.899300503403;19214.711779323323;19663.41009769709;19886.602267954848;19886.90056549708;19905.303314383927;10739.892601073889;11092.778144437227;11094.722631934104;11096.833547496793;11105.83341249882;8018.639161237642;8047.51714897108;8224.424290299861;8311.376646751396;8508.532030738368;17080.23138958775;17427.128643568023;17530.561735956704;17551.385701500385;17795.377161799275;17515;17777.822221777617;17860.106994650476;18061.367852125113;18095.81904180994;11632.127590430582;11645.359505227294;11730.12024098179;11752.35362055061;11786.882131178578;13014.414351354022;13246.470141194244;13251.602451926457;13268.13757105785;13270.27013514266;10821.080208182393;10861.728456788644;11028.834567481334;11030.117590592572;11075.667729968114;10506.106980906708;10594.258401911793;10691.946540267249;10705.036546710833;10745.946270268598;9520.47637379923;9700.175485083846;9738;9741.902580974383;9751.804963900824;6908.516403851682;6915.308469153246;6939.618320992192;6973.4072603829245;6982;6938.167419909643;6943.375096241362;7045.1898031727005;7067.960776865792;7147.928520714934 11:20:39 INFO - PID 3516 | |4;prototype.html;476.35453959884376;497.1379904533661;510.573670984728;516.3519782672745;520.5081198267637;6117.1249512729;6143.938560614398;6223.688815559227;6240.563160578761;6276.905846412296;5767.163761254616;5978.551608629348;5998.940010599904;6000.849978750533;6068.241469816273;4812.711237325754;4813.4223893132785;4845.933894543195;4886.462489126193;4891.241857512091;4794.736289504076;4855.393075865517;4895;4910.582600478963;4912.771807048238;4857.684250523723;4864.659473836833;4901.436334821487;4903.583195428375;4908.803647854082;4850.223964165734;4866.223828302679;4900.754962251873;4905.803767849317;4946.9752651236695;4396.60430561251;4412.24991751403;4433.822647094113;4442.534822838192;4465.307877279027;5876.764929402819;5981.850453738648;5985;6001.549883758736;6023.578349515536;5604.663720176803;5656.830295091154;5679.876068162494;5740.397258287903;5743.54051675865;6502.60984340941;6577.506686998447;6580.868382632369;6585.209774827052;6601.966990165067;4760.309755085528;4829.517048295177;4856.538628830255;4862.927056094161;4866.270059491069;6677.8664426711675;6851.588904665736;6865.141857267841;6942.583444993316;6957.860842783167;4926.9014619707405;4951.876203094879;4977.477364876636;4979.576735977483;4983.651144419856;4984.3021976923255;5001.474845141208;5066.164082926276;5085.771140298695;5153.45888681683;5598.356189038232;5610.410906854803;5650.231386620527;5662.37713851476;5673.744681489343;6172.197614310201;6499.772507962199;6528.281888992207;6552.57408268461;6652.600843949379;1659.3279721712727;1667.674803413347;1705.8208888066822;1726.2490816494865;1737.1053907237783;1035.7255327338262;1202.6933132051272;1205.2406983600276;1219.6280134559045;1222.3032871263472;5606.635568688103;5655.688937108499;5669.518090962314;5676.962824888665;5679.545636349082;6594.988528498854;6631.535792494576;6675.0988616537115;6686.732530698718;6785.710714964141;5732.77068917247;5763.481286684135;5772.480476757112;5807.767689292466;5857.6820215452035;3576.499290099336;3589.838457269429;3603.675669189733;3630.7640003399692;3639.0356555512813;6567.540272180901;6587.209534855752;6590.176227971504;6614.0740296358545;6619.86760264792;6920.7231710731;6927.411170050501;6933.930660693329;6945;6945.93054069463;6537.8692426152165;6603.306652801482;6625.4037136657935;6635.004749287549;6651.401373876374;6751.56114852533;6762.37702951298;6771.322867713189;6779.15260592426;6799.184097908084;6676.76631317921;6720.966395167993;6734.797956061326;6745.52781305304;6769;6495.1556297681;6533.444657204191;6536.607803531803;6537.640429776408;6601.3728695773825;4973.875653108701;4980.103581355246;4991.376077990251;5026.220935754964;5048.242763585492;6652.800415987334;6720.899186512304;6740.966295168493;6803.285655006153;6822.8635427292165;6368.395002474757;6412.2625898022325;6441.774537891151;6558.376954189344;6574.638394888327;6443.484521238196;6469.77355792545;6478.70845811949;6500.869982600416;6575.013747937847;6323.241211054703;6358;6385.584936979081;6449.871002580016;6473.77341793035;6265.5226349376535;6428.164338635947;6452.032000319676;6486.318936511787;6531.673416329259;5732.7706891723865;6066.908996365147;6136.23297087864;6284.340144284784;6323.660245709117 11:20:39 INFO - PID 3516 | |5;yui.html;886.401678866765;899.1952202778517;928.2388441477991;929.8605209218621;931.1014870649822;18702.812971870295;19319.13063912124;19328.130234139462;19360.128794204276;22172.33482995513;21075.578488430223;21926.122955081777;21958;21968.670469942965;22195.223167189153;1026.743314171459;1056.3450660590406;1056.5773690523774;1056.6301794371948;1057.5029736024094;1056.614335767447;1057.3391630231106;1060.1677683018831;1060.3955745225226;1066.610687099211;1069.3423544520142;1118.4966764955802;1124.8537690100316;1125.0437128440842;1125.5329038449033;1108.5233349659643;1114.1755101225117;1114.4929057278919;1114.554178328667;1116.8380584815156;801.887735717;803.9316658084069;803.9638216280281;804.3283857978595;804.3806269172704;20080.397588072236;20202.19191232349;20234.089465974066;20346.898265508727;20930.686039709417;21025;21611.891940540354;21745.238916638;22161.113555457923;22169.113235470562;17137.742933856003;17159.914200428917;17243.913780431016;17410.30358785647;17695.5576110597;3688.686461650763;3694.353488139565;3696.2237930034726;3712.201876596545;3729.4965179700666;125325.37337313347;125778;129624;130067.34966325203;130716.3464182673;16458.75311870299;17123.486295411156;17183.3985810496;17395.91302043482;17995.640087198182;30540.694593054228;31041.379172416426;31108;31571.684283157334;31908.202294942814;7528.736494222676;7867.134615192324;8168.673253069811;8509.914900850912;8604.440711353742;4135.193637240769;4190.811413486401;4235.089455767024;4247.766372849523;4250.447441832542;5046.596272298208;5157.819476318386;5209.791608335624;5218.947810521846;5317.175837745156;993.4138858073627;998.6005597760953;1019.6889948565758;1024.2215915903967;1027.1782573940818;618.5979113576121;651.0787236060921;704.6582407532343;708.1643660480579;725.793148952546;11803.704907377385;11866.406679665983;11867.821982670266;12052.517899284107;13681.24753138567;674.1034424215782;676.2088356622763;678.8947713104477;681.4752640466912;692.6086760980131;5363.168708850135;5519;5559.972200138974;5574.704031170404;5722.427757224327;106758;108701;109511;113220;113504.43247783874;92795;94556.52721736486;97272.51363743136;97520;99238;81146.18853811386;83648.58175709083;84429;86245.13754862496;87654;11977.760444791229;12705.42825572833;12817.807732884201;12858.807117893426;13061.151025183333;38564.80717596394;38848.2230355397;39375.40936885892;39790.60209397869;39966.80016599898;68664.31335686779;71282.28717712757;72058.63970680113;72558.27441725515;73167.26832731818;19352;19659.70510442373;19714.901425492782;19904.90047549753;19939.202431902562;21818.89090554537;22056.33830985073;22203.444913876636;22645.660315095618;22729.88635056814;19292.807071929103;19297.5175620605;19338.323158689378;19371.225150993803;19394.612107758046;19770.70343944813;20042;20289.79710202879;20385.59228815445;20669;18669.906650466663;19529;19937.501562461053;19993.900030499753;20154.69767953511;13941.442342306194;14136.929315353358;14203.715925681221;14205.289735512977;14266.358013889192;14253.35859886287;14446;14521.056131351428;14608.780868287196;14609.634759131106 11:20:39 INFO - PID 3516 | __end_tp_report 11:20:39 INFO - PID 3516 | __start_cc_report 11:20:39 INFO - PID 3516 | _x_x_mozilla_cycle_collect,367 11:20:39 INFO - PID 3516 | __end_cc_report 11:20:39 INFO - PID 3516 | __startTimestamp1502907639980__endTimestamp 11:20:39 INFO - PID 3516 | 11:20:39 INFO - PID 3516 | ------- Summary: start ------- 11:20:39 INFO - PID 3516 | Number of tests: 6 11:20:39 INFO - PID 3516 | 11:20:39 INFO - PID 3516 | [#0] dojo.html Cycles:165 Average:6078.38 Median:6331.87 stddev:701.83 (11.1%) stddev-sans-first:665.57 11:20:39 INFO - PID 3516 | Values: 3159.2 3172.3 3179.1 3206.6 3212.2 6175.0 6209.5 6233.0 6240.4 6275.0 5915.3 6008.9 6009.1 6023.8 6029.7 5554.9 5567.7 5585.3 5588.7 5602.9 5617.7 5622.1 5622.8 5648.4 5648.6 5687.0 5708.0 5718.3 5726.6 5812.3 5667.0 5742.3 5748.3 5748.3 5751.5 5446.3 5492.7 5553.9 5567.1 5654.6 5336.1 5910.9 5984.7 6048.2 6150.4 6574.8 6582.9 6586.7 6651.3 6654.1 6487.3 6557.9 6579.1 6652.8 6745.2 6111.3 6124.3 6129.0 6179.8 6261.4 6796.9 6815.5 6819.2 6820.7 6823.3 6335.2 6441.1 6496.5 6520.4 6532.0 6559.5 6584.1 6638.5 6656.8 6663.1 6380.3 6491.4 6492.0 6499.7 6503.1 6500.3 6556.0 6606.9 6677.8 6678.4 5446.6 5451.2 5454.2 5464.1 5469.5 4561.1 4655.3 4656.0 4677.6 4684.2 5630.4 5743.1 5766.5 5834.9 5835.2 6602.6 6618.4 6627.5 6629.5 6630.0 5643.7 5715.5 5717.4 5718.0 5729.9 5863.2 5941.6 5946.3 5949.6 5952.0 5924.6 6405.8 6495.1 6497.0 6521.5 6619.7 6651.3 6652.5 6671.5 6685.1 6397.6 6429.6 6516.1 6517.3 6529.9 6483.9 6494.0 6575.1 6585.2 6592.0 6473.6 6608.6 6623.7 6624.1 6628.5 6305.4 6384.5 6387.8 6397.1 6398.8 6366.4 6398.9 6469.1 6478.7 6492.0 6290.1 6368.2 6427.8 6450.2 6456.2 6372.7 6424.7 6490.7 6500.3 6507.7 6180.6 6267.0 6317.4 6320.9 6331.9 11:20:39 INFO - PID 3516 | 11:20:39 INFO - PID 3516 | [#1] ext.html Cycles:180 Average:38911.01 Median:3553.48 stddev:57214.08 (1610.1%) stddev-sans-first:57303.00 11:20:39 INFO - PID 3516 | Values: 794.9 837.9 856.8 859.9 1165.0 40920.6 41272.6 41398.8 41425.8 41465.4 41511.4 41978.4 41998.0 42005.8 42036.6 7367.2 7498.6 7503.2 7535.0 7556.0 7366.8 7479.3 7479.4 7482.5 7488.7 7337.0 7482.7 7556.2 7563.3 7565.2 7440.9 7449.2 7458.2 7479.4 7487.6 7247.8 7405.9 7434.9 7459.7 7480.4 37100.0 37236.8 37414.0 37425.8 37543.8 3483.3 3538.9 3553.2 3553.8 3556.8 30480.0 30494.7 30527.1 30586.5 30605.2 5023.2 5043.5 5055.2 5057.7 5064.8 191007.0 194177.0 194853.0 195596.0 195687.0 48184.8 48313.5 48314.0 48515.0 48528.5 50262.7 50270.5 50420.0 50454.5 50522.0 15546.5 15575.0 15583.6 15660.6 15666.2 12762.8 12838.0 12839.5 12842.8 12845.4 12685.8 12841.6 14294.8 14325.2 14343.3 6036.4 6105.8 6178.5 6217.1 6285.5 1006.3 1025.3 1026.8 1031.5 1033.0 23471.9 24163.3 24168.9 24453.1 24569.5 1102.3 1116.8 1117.3 1119.4 1138.4 12500.2 12545.6 12589.4 12616.2 12734.0 251009.0 252901.0 253222.0 253897.0 254594.0 51121.0 51579.2 51582.2 51656.7 51783.5 1457.0 1468.4 1479.1 1491.7 1496.7 22767.8 23024.4 23043.4 23119.7 23172.7 37160.6 37164.6 37205.3 37210.4 37268.4 209381.0 209500.0 209947.0 209992.0 210297.0 35463.5 35499.5 35631.3 35827.3 35849.1 45832.5 46433.3 46491.8 46842.0 47028.8 33223.5 33597.5 33796.0 35257.9 35357.5 33329.3 33798.8 33975.5 34054.5 34461.8 36880.4 37022.8 37114.8 37168.0 37400.0 33354.2 33471.7 33711.7 34200.3 34205.7 23180.8 25296.5 25556.7 25990.7 26096.7 11:20:39 INFO - PID 3516 | 11:20:39 INFO - PID 3516 | [#2] jquery.html Cycles:180 Average:37667.10 Median:22610.44 stddev:91722.61 (405.7%) stddev-sans-first:91937.62 11:20:40 INFO - PID 3516 | Values: 567.5 568.8 570.3 583.5 584.4 21789.2 22683.8 22958.0 22998.8 23015.3 26612.3 27055.0 27070.0 27106.2 27327.3 6491.1 6633.2 6638.1 6638.5 6639.9 5954.9 6476.5 6485.9 6633.8 6690.9 6435.1 6447.3 6448.8 6470.5 6473.8 6428.4 6447.2 6454.8 6456.4 6457.2 5969.0 6000.5 6018.5 6038.8 6039.4 29407.3 29920.7 30385.5 30706.7 31263.7 1886.2 1912.5 1918.8 1919.3 1923.7 19319.5 19924.6 19939.0 19976.0 19995.0 2192.4 2245.6 2251.5 2315.7 2455.5 137412.3 140528.0 143452.0 143470.0 144264.3 14170.8 14556.1 14835.5 14837.0 14944.8 22985.3 22992.5 23030.9 23037.9 23058.5 10286.2 10436.7 10507.3 10616.8 10648.5 9842.9 9897.9 9947.9 10001.1 10171.7 7577.7 8840.6 9135.2 9188.8 9569.1 2570.9 2577.0 2579.4 2611.4 2750.0 917.4 930.8 936.4 946.2 977.0 19641.0 19908.7 20047.8 20178.6 20354.6 1020.6 1021.3 1049.3 1053.8 1060.2 10601.7 11093.0 11217.5 11240.2 11303.4 506804.0 551884.0 555893.0 558506.0 561621.0 116197.4 118220.4 119453.4 121664.0 123591.0 1203.0 1271.1 1306.4 1318.1 1326.7 20914.9 20915.0 21082.5 21299.9 21420.4 59141.0 60798.4 60859.7 60863.7 61733.7 71605.6 83489.6 84129.6 85795.0 86270.6 21687.7 22273.7 22511.9 22522.2 22537.1 24393.6 24523.5 24602.6 25296.1 25329.7 18608.3 19032.9 19479.7 19532.5 19532.7 15785.0 15812.4 16339.8 17099.5 17559.3 18696.0 19489.7 19546.4 19671.7 19712.6 21176.4 21203.7 21294.7 21343.8 21358.4 18980.0 19044.7 19050.7 19167.2 19251.9 11:20:40 INFO - PID 3516 | 11:20:40 INFO - PID 3516 | [#3] mootools.html Cycles:180 Average:8958.06 Median:2620.61 stddev:6240.45 (238.1%) stddev-sans-first:6231.83 11:20:40 INFO - PID 3516 | Values: 1358.8 1421.2 1423.0 1427.5 1428.4 13808.6 14445.3 14447.9 14451.2 14467.3 14665.7 14777.6 14833.9 14853.2 14856.4 2615.6 2683.1 2684.5 2684.9 2696.8 2534.0 2717.1 2722.9 2727.9 2729.6 2725.4 2740.1 2761.0 2783.5 2785.7 2680.2 2711.2 2718.4 2722.9 2732.9 2593.7 2602.3 2610.2 2611.2 2625.6 14108.4 14297.1 14312.6 14326.3 14331.4 7390.5 7546.1 7768.3 7788.0 7836.8 4879.5 4995.1 5105.4 5114.5 5265.4 1802.5 1884.7 2245.8 2283.7 2334.7 23802.8 24017.6 24099.4 24153.3 24227.0 6565.3 6702.2 6748.1 6748.3 6902.0 18558.0 18614.4 18663.9 18680.8 18683.7 7734.1 7795.1 7803.1 7814.3 7841.6 6781.7 6954.5 6954.7 7040.5 7066.5 5364.0 5412.6 5498.7 5536.3 5587.7 1665.6 1689.5 1714.6 1722.6 1731.0 838.9 858.4 875.0 886.6 889.8 6967.7 6973.2 6995.4 7020.1 7059.5 709.6 713.4 713.8 723.8 730.3 4334.3 4651.1 5179.1 5249.4 5276.6 19491.2 20106.4 20118.1 20125.4 20139.9 19214.7 19663.4 19886.6 19886.9 19905.3 10739.9 11092.8 11094.7 11096.8 11105.8 8018.6 8047.5 8224.4 8311.4 8508.5 17080.2 17427.1 17530.6 17551.4 17795.4 17515.0 17777.8 17860.1 18061.4 18095.8 11632.1 11645.4 11730.1 11752.4 11786.9 13014.4 13246.5 13251.6 13268.1 13270.3 10821.1 10861.7 11028.8 11030.1 11075.7 10506.1 10594.3 10691.9 10705.0 10745.9 9520.5 9700.2 9738.0 9741.9 9751.8 6908.5 6915.3 6939.6 6973.4 6982.0 6938.2 6943.4 7045.2 7068.0 7147.9 11:20:40 INFO - PID 3516 | 11:20:40 INFO - PID 3516 | [#4] prototype.html Cycles:180 Average:5475.31 Median:5991.97 stddev:1553.01 (25.9%) stddev-sans-first:1511.36 11:20:40 INFO - PID 3516 | Values: 476.4 497.1 510.6 516.4 520.5 6117.1 6143.9 6223.7 6240.6 6276.9 5767.2 5978.6 5998.9 6000.8 6068.2 4812.7 4813.4 4845.9 4886.5 4891.2 4794.7 4855.4 4895.0 4910.6 4912.8 4857.7 4864.7 4901.4 4903.6 4908.8 4850.2 4866.2 4900.8 4905.8 4947.0 4396.6 4412.2 4433.8 4442.5 4465.3 5876.8 5981.9 5985.0 6001.5 6023.6 5604.7 5656.8 5679.9 5740.4 5743.5 6502.6 6577.5 6580.9 6585.2 6602.0 4760.3 4829.5 4856.5 4862.9 4866.3 6677.9 6851.6 6865.1 6942.6 6957.9 4926.9 4951.9 4977.5 4979.6 4983.7 4984.3 5001.5 5066.2 5085.8 5153.5 5598.4 5610.4 5650.2 5662.4 5673.7 6172.2 6499.8 6528.3 6552.6 6652.6 1659.3 1667.7 1705.8 1726.2 1737.1 1035.7 1202.7 1205.2 1219.6 1222.3 5606.6 5655.7 5669.5 5677.0 5679.5 6595.0 6631.5 6675.1 6686.7 6785.7 5732.8 5763.5 5772.5 5807.8 5857.7 3576.5 3589.8 3603.7 3630.8 3639.0 6567.5 6587.2 6590.2 6614.1 6619.9 6920.7 6927.4 6933.9 6945.0 6945.9 6537.9 6603.3 6625.4 6635.0 6651.4 6751.6 6762.4 6771.3 6779.2 6799.2 6676.8 6721.0 6734.8 6745.5 6769.0 6495.2 6533.4 6536.6 6537.6 6601.4 4973.9 4980.1 4991.4 5026.2 5048.2 6652.8 6720.9 6741.0 6803.3 6822.9 6368.4 6412.3 6441.8 6558.4 6574.6 6443.5 6469.8 6478.7 6500.9 6575.0 6323.2 6358.0 6385.6 6449.9 6473.8 6265.5 6428.2 6452.0 6486.3 6531.7 5732.8 6066.9 6136.2 6284.3 6323.7 11:20:40 INFO - PID 3516 | 11:20:40 INFO - PID 3516 | [#5] yui.html Cycles:180 Average:24164.58 Median:20799.84 stddev:32150.83 (154.6%) stddev-sans-first:32193.50 11:20:40 INFO - PID 3516 | Values: 886.4 899.2 928.2 929.9 931.1 18702.8 19319.1 19328.1 19360.1 22172.3 21075.6 21926.1 21958.0 21968.7 22195.2 1026.7 1056.3 1056.6 1056.6 1057.5 1056.6 1057.3 1060.2 1060.4 1066.6 1069.3 1118.5 1124.9 1125.0 1125.5 1108.5 1114.2 1114.5 1114.6 1116.8 801.9 803.9 804.0 804.3 804.4 20080.4 20202.2 20234.1 20346.9 20930.7 21025.0 21611.9 21745.2 22161.1 22169.1 17137.7 17159.9 17243.9 17410.3 17695.6 3688.7 3694.4 3696.2 3712.2 3729.5 125325.4 125778.0 129624.0 130067.3 130716.3 16458.8 17123.5 17183.4 17395.9 17995.6 30540.7 31041.4 31108.0 31571.7 31908.2 7528.7 7867.1 8168.7 8509.9 8604.4 4135.2 4190.8 4235.1 4247.8 4250.4 5046.6 5157.8 5209.8 5218.9 5317.2 993.4 998.6 1019.7 1024.2 1027.2 618.6 651.1 704.7 708.2 725.8 11803.7 11866.4 11867.8 12052.5 13681.2 674.1 676.2 678.9 681.5 692.6 5363.2 5519.0 5560.0 5574.7 5722.4 106758.0 108701.0 109511.0 113220.0 113504.4 92795.0 94556.5 97272.5 97520.0 99238.0 81146.2 83648.6 84429.0 86245.1 87654.0 11977.8 12705.4 12817.8 12858.8 13061.2 38564.8 38848.2 39375.4 39790.6 39966.8 68664.3 71282.3 72058.6 72558.3 73167.3 19352.0 19659.7 19714.9 19904.9 19939.2 21818.9 22056.3 22203.4 22645.7 22729.9 19292.8 19297.5 19338.3 19371.2 19394.6 19770.7 20042.0 20289.8 20385.6 20669.0 18669.9 19529.0 19937.5 19993.9 20154.7 13941.4 14136.9 14203.7 14205.3 14266.4 14253.4 14446.0 14521.1 14608.8 14609.6 11:20:40 INFO - PID 3516 | -------- Summary: end -------- 11:20:40 INFO - PID 3516 | 11:20:40 INFO - TEST-INFO | 3516: exit 0 11:20:40 INFO - TEST-OK | dromaeo_css | took 1113123ms 11:20:40 INFO - TEST-START | kraken 11:20:40 INFO - Initialising browser for kraken test... 11:20:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49071/getInfo.html -profile /tmp/tmpwbUHR5/profile 11:20:40 INFO - TEST-INFO | started process 3888 (/builds/slave/test/build/application/firefox/firefox http://localhost:49071/getInfo.html) 11:20:46 INFO - TEST-INFO | 3888: exit 0 11:20:46 INFO - Browser initialized. 11:20:46 INFO - Running cycle 1/1 for kraken test... 11:20:46 INFO - TEST-INFO | started process 4101 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwbUHR5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:20:47 INFO - PID 4101 | 11:20:47 INFO - PID 4101 | (/builds/slave/test/build/application/firefox/firefox:4150): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:20:47 INFO - PID 4101 | 11:20:57 INFO - PID 4101 | RSS: Main: 165117952 11:20:57 INFO - PID 4101 | 11:21:00 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49071/tests/kraken/driver.html?testName=audio-beat-detection) 11:21:01 INFO - PID 4101 | RSS: Main: 171499520 11:21:01 INFO - PID 4101 | 11:21:04 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49071/tests/kraken/driver.html?testName=audio-dft) 11:21:04 INFO - PID 4101 | RSS: Main: 175198208 11:21:04 INFO - PID 4101 | 11:21:07 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49071/tests/kraken/driver.html?testName=audio-fft) 11:21:08 INFO - PID 4101 | RSS: Main: 168980480 11:21:08 INFO - PID 4101 | 11:21:10 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49071/tests/kraken/driver.html?testName=audio-oscillator) 11:21:10 INFO - PID 4101 | RSS: Main: 169623552 11:21:10 INFO - PID 4101 | 11:21:13 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49071/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:21:13 INFO - PID 4101 | RSS: Main: 169766912 11:21:13 INFO - PID 4101 | 11:21:16 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49071/tests/kraken/driver.html?testName=imaging-darkroom) 11:21:17 INFO - PID 4101 | RSS: Main: 171208704 11:21:17 INFO - PID 4101 | 11:21:20 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49071/tests/kraken/driver.html?testName=imaging-desaturate) 11:21:21 INFO - PID 4101 | RSS: Main: 171040768 11:21:21 INFO - PID 4101 | 11:21:24 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49071/tests/kraken/driver.html?testName=json-parse-financial) 11:21:24 INFO - PID 4101 | RSS: Main: 171143168 11:21:24 INFO - PID 4101 | 11:21:26 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49071/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:21:27 INFO - PID 4101 | RSS: Main: 171286528 11:21:27 INFO - PID 4101 | 11:21:28 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49071/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:21:29 INFO - PID 4101 | RSS: Main: 171433984 11:21:29 INFO - PID 4101 | 11:21:31 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49071/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:21:32 INFO - PID 4101 | RSS: Main: 171503616 11:21:32 INFO - PID 4101 | 11:21:34 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:21:35 INFO - PID 4101 | RSS: Main: 171626496 11:21:35 INFO - PID 4101 | 11:21:38 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:21:39 INFO - PID 4101 | RSS: Main: 172036096 11:21:39 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | Cycle 1(1): loaded http://localhost:49071/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49071/tests/kraken/driver.html?testName=ai-astar) 11:21:41 INFO - PID 4101 | RSS: Main: 173359104 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | __start_tp_report 11:21:41 INFO - PID 4101 | _x_x_mozilla_page_load 11:21:41 INFO - PID 4101 | _x_x_mozilla_page_load_details 11:21:41 INFO - PID 4101 | |i|pagename|runs| 11:21:41 INFO - PID 4101 | |0;ai-astar;98;107;100;108;107;109;110;101;108;109 11:21:41 INFO - PID 4101 | |1;audio-beat-detection;147;116;142;180;117;147;143;119;175;122 11:21:41 INFO - PID 4101 | |2;audio-dft;164;162;162;163;163;164;163;164;171;164 11:21:41 INFO - PID 4101 | |3;audio-fft;75;76;74;176;77;75;74;76;149;75 11:21:41 INFO - PID 4101 | |4;audio-oscillator;78;78;130;80;79;78;125;79;79;129 11:21:41 INFO - PID 4101 | |5;imaging-gaussian-blur;114;115;113;114;113;113;113;114;113;114 11:21:41 INFO - PID 4101 | |6;imaging-darkroom;150;150;151;151;150;150;149;149;149;149 11:21:41 INFO - PID 4101 | |7;imaging-desaturate;93;94;93;93;94;94;94;98;94;94 11:21:41 INFO - PID 4101 | |8;json-parse-financial;55;62;53;53;53;54;54;54;54;54 11:21:41 INFO - PID 4101 | |9;json-stringify-tinderbox;55;55;55;55;55;55;55;56;54;54 11:21:41 INFO - PID 4101 | |10;stanford-crypto-aes;75;75;81;76;75;75;76;76;78;75 11:21:41 INFO - PID 4101 | |11;stanford-crypto-ccm;128;133;122;130;129;118;126;122;118;170 11:21:41 INFO - PID 4101 | |12;stanford-crypto-pbkdf2;185;181;179;199;177;176;178;180;186;173 11:21:41 INFO - PID 4101 | |13;stanford-crypto-sha256-iterative;67;69;73;66;68;68;66;69;68;67 11:21:41 INFO - PID 4101 | __end_tp_report 11:21:41 INFO - PID 4101 | __start_cc_report 11:21:41 INFO - PID 4101 | _x_x_mozilla_cycle_collect,969 11:21:41 INFO - PID 4101 | __end_cc_report 11:21:41 INFO - PID 4101 | __startTimestamp1502907701461__endTimestamp 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | ------- Summary: start ------- 11:21:41 INFO - PID 4101 | Number of tests: 14 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#0] ai-astar Cycles:10 Average:105.70 Median:108.50 stddev:4.32 (4.0%) stddev-sans-first:3.57 11:21:41 INFO - PID 4101 | Values: 98.0 107.0 100.0 108.0 107.0 109.0 110.0 101.0 108.0 109.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#1] audio-beat-detection Cycles:10 Average:140.80 Median:145.00 stddev:23.09 (15.9%) stddev-sans-first:24.38 11:21:41 INFO - PID 4101 | Values: 147.0 116.0 142.0 180.0 117.0 147.0 143.0 119.0 175.0 122.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.00 stddev:2.58 (1.6%) stddev-sans-first:2.74 11:21:41 INFO - PID 4101 | Values: 164.0 162.0 162.0 163.0 163.0 164.0 163.0 164.0 171.0 164.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#3] audio-fft Cycles:10 Average:92.70 Median:75.00 stddev:37.35 (49.8%) stddev-sans-first:39.06 11:21:41 INFO - PID 4101 | Values: 75.0 76.0 74.0 176.0 77.0 75.0 74.0 76.0 149.0 75.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#4] audio-oscillator Cycles:10 Average:93.50 Median:78.50 stddev:23.85 (30.4%) stddev-sans-first:24.63 11:21:41 INFO - PID 4101 | Values: 78.0 78.0 130.0 80.0 79.0 78.0 125.0 79.0 79.0 129.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 11:21:41 INFO - PID 4101 | Values: 114.0 115.0 113.0 114.0 113.0 113.0 113.0 114.0 113.0 114.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#6] imaging-darkroom Cycles:10 Average:149.80 Median:150.00 stddev:0.79 (0.5%) stddev-sans-first:0.83 11:21:41 INFO - PID 4101 | Values: 150.0 150.0 151.0 151.0 150.0 150.0 149.0 149.0 149.0 149.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:1.45 (1.5%) stddev-sans-first:1.48 11:21:41 INFO - PID 4101 | Values: 93.0 94.0 93.0 93.0 94.0 94.0 94.0 98.0 94.0 94.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:54.00 stddev:2.67 (5.0%) stddev-sans-first:2.83 11:21:41 INFO - PID 4101 | Values: 55.0 62.0 53.0 53.0 53.0 54.0 54.0 54.0 54.0 54.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#9] json-stringify-tinderbox Cycles:10 Average:54.90 Median:55.00 stddev:0.57 (1.0%) stddev-sans-first:0.60 11:21:41 INFO - PID 4101 | Values: 55.0 55.0 55.0 55.0 55.0 55.0 55.0 56.0 54.0 54.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:76.00 stddev:1.93 (2.5%) stddev-sans-first:2.00 11:21:41 INFO - PID 4101 | Values: 75.0 75.0 81.0 76.0 75.0 75.0 76.0 76.0 78.0 75.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:128.50 stddev:15.07 (11.7%) stddev-sans-first:15.97 11:21:41 INFO - PID 4101 | Values: 128.0 133.0 122.0 130.0 129.0 118.0 126.0 122.0 118.0 170.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.40 Median:180.50 stddev:7.32 (4.1%) stddev-sans-first:7.65 11:21:41 INFO - PID 4101 | Values: 185.0 181.0 179.0 199.0 177.0 176.0 178.0 180.0 186.0 173.0 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:2.02 (3.0%) stddev-sans-first:2.11 11:21:41 INFO - PID 4101 | Values: 67.0 69.0 73.0 66.0 68.0 68.0 66.0 69.0 68.0 67.0 11:21:41 INFO - PID 4101 | -------- Summary: end -------- 11:21:41 INFO - PID 4101 | 11:21:41 INFO - PID 4101 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:21:42 INFO - TEST-INFO | 4101: exit 0 11:21:42 INFO - TEST-OK | kraken | took 61448ms 11:21:42 INFO - SUITE-END | took 1174s 11:21:42 INFO - Completed test suite (00:19:35) 11:21:42 INFO - javascript benchmark 11:21:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8582.202818450436, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3159.1786135604743, 3172.2545201877556, 3179.0621766578856, 3206.567113439685, 3212.164837142344, 6175.0, 6209.534284928632, 6232.968835155823, 6240.43836054755, 6274.968625156879, 5915.349311575723, 6008.939910600892, 6009.06859436788, 6023.789167379143, 6029.698515074239, 5554.888902221953, 5567.66594004361, 5585.273914391123, 5588.6646801191955, 5602.887942241153, 5617.719114044302, 5622.128570071645, 5622.803201887935, 5648.350439699426, 5648.576356773238, 5687.0, 5707.971460142693, 5718.285214348207, 5726.599138060337, 5812.331581868076, 5666.971665141689, 5742.310922689262, 5748.281464816877, 5748.281464816898, 5751.482366586985, 5446.346438427375, 5492.725363731837, 5553.944460555424, 5567.053600887859, 5654.6041777075625, 5336.146216605324, 5910.940890591125, 5984.700764961778, 6048.243969503812, 6150.446459818638, 6574.8356291093105, 6582.868342633168, 6586.7035983380865, 6651.301613330627, 6654.10169627099, 6487.253965793947, 6557.93442065578, 6579.078928949951, 6652.800415987528, 6745.1905771307265, 6111.2666480022235, 6124.326324104345, 6129.0, 6179.84550386244, 6261.405166509175, 6796.898046529306, 6815.454763618898, 6819.181698196248, 6820.727170913207, 6823.283555226729, 6335.176427064544, 6441.0982462455295, 6496.545241833121, 6520.445762110179, 6531.987541931008, 6559.508036897157, 6584.078229047937, 6638.468922486286, 6656.833579160463, 6663.06717059612, 6380.266269378988, 6491.383318584727, 6492.0261960706275, 6499.740010399626, 6503.122078519339, 6500.284968653445, 6555.967220163869, 6606.900896486556, 6677.799666010027, 6678.365555272241, 5446.564274858002, 5451.182322651554, 5454.181872719124, 5464.098423760114, 5469.480399362054, 4561.087782443525, 4655.301704744315, 4655.976720116378, 4677.6257899367965, 4684.156851766715, 5630.40880707528, 5743.1385292206505, 5766.452187042224, 5834.883302334015, 5835.183074369592, 6602.570832895846, 6618.404343609098, 6627.469802415795, 6629.535932484776, 6630.0, 5643.661380317194, 5715.514181294637, 5717.399673034354, 5717.999350113713, 5729.942700572941, 5863.208466857005, 5941.55438342126, 5946.256717910262, 5949.613275137102, 5951.970240148771, 5924.61490003157, 6405.775797847053, 6495.090687303781, 6496.9929660902635, 6521.510886683518, 6619.735210591715, 6651.26836048044, 6652.534322597372, 6671.532992690465, 6685.130932978682, 6397.616143031433, 6429.58207716497, 6516.120323756232, 6517.283098859217, 6529.869402611826, 6483.935160648333, 6494.0258961156205, 6575.145231119924, 6585.176852893388, 6592.0, 6473.611583305017, 6608.603483790988, 6623.701933412912, 6624.105745724266, 6628.469722422291, 6305.432511074026, 6384.457321127757, 6387.776427825004, 6397.104405383157, 6398.840028999312, 6366.395192456709, 6398.872022559616, 6469.061986012096, 6478.740850366121, 6492.0, 6290.0879372491645, 6368.172137622079, 6427.807165785034, 6450.225972883284, 6456.192975878015, 6372.74509019634, 6424.710888010144, 6490.7079181436, 6500.284968653539, 6507.73969041252, 6180.598261113013, 6267.0, 6317.431431171033, 6320.873582528416, 6331.873362532631], "value": 6027.651896483036, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.9165337639548, 837.9245867871892, 856.790086428825, 859.8624220124781, 1165.021382039087, 40920.59079409205, 41272.58727412725, 41398.793006035005, 41425.79287103564, 41465.378019329735, 41511.37732934008, 41978.37032444516, 41998.0, 42005.78997105018, 42036.57963420365, 7367.18960914299, 7498.587577683225, 7503.174650788409, 7535.0, 7555.962220188919, 7366.77899663011, 7479.326860582546, 7479.3642540384135, 7482.51363661363, 7488.737894173705, 7336.963315183417, 7482.6632801524065, 7556.244375562427, 7563.319301262885, 7565.243475652445, 7440.888386674177, 7449.180590135081, 7458.216887226845, 7479.3642540384135, 7487.588182649952, 7247.818804529877, 7405.8889116663295, 7434.9256507434775, 7459.738909138154, 7480.364169045583, 37100.0, 37236.81381593102, 37414.0, 37425.81287093574, 37543.81228093842, 3483.3381657484997, 3538.9469157962653, 3553.218291975761, 3553.751237413382, 3556.8043757593323, 30480.0, 30494.69505304963, 30527.08418747441, 30586.541201881988, 30605.234869128228, 5023.246513023039, 5043.520865517807, 5055.165897626887, 5057.69653820772, 5064.82273120439, 191007.0, 194177.0, 194853.0, 195596.0, 195687.0, 48184.7590762051, 48313.516864830905, 48314.0, 48515.0, 48528.5147148531, 50262.748686257066, 50270.497295027315, 50420.0, 50454.49545504498, 50522.0, 15546.533603991898, 15574.987625804286, 15583.610409739847, 15660.608484787972, 15666.216689165496, 12762.80855787164, 12838.0, 12839.48642054326, 12842.807357889638, 12845.357732113356, 12685.809712854314, 12841.614751557468, 14294.785578216335, 14325.212113333866, 14343.282835858374, 6036.366181550961, 6105.8473538161015, 6178.5366097542865, 6217.077973352517, 6285.491482044368, 1006.2955930848435, 1025.2566889005411, 1026.7741096958657, 1031.4584842957506, 1032.9896701033044, 23471.88264058669, 24163.275101746975, 24168.87915560411, 24453.144139955373, 24569.508609827702, 1102.3385968418884, 1116.7989761842946, 1117.2570240789946, 1119.356370087203, 1138.3738943580997, 12500.249985000748, 12545.560905368267, 12589.433475493624, 12616.17994830333, 12734.04494662904, 251009.0, 252901.0, 253222.0, 253897.0, 254594.0, 51121.0, 51579.22631160536, 51582.226266606034, 51656.74171629118, 51783.48216517787, 1456.9635759106106, 1468.3906178935836, 1479.0681870421565, 1491.7016596680708, 1496.7006598680089, 22767.772322276564, 23024.4243893904, 23043.423914401603, 23119.653205201597, 23172.652410213523, 37160.62839371571, 37164.6283537172, 37205.25589488141, 37210.44184337183, 37268.440973385965, 209381.0, 209500.0, 209947.0, 209992.0, 210297.0, 35463.46804797982, 35499.46750798688, 35631.28737425185, 35827.283454330245, 35849.1037724059, 45832.541674582826, 46433.30350044819, 46491.76754116343, 46842.0, 47028.7648561755, 33223.501647474826, 33597.49603755995, 33796.0, 35257.94226173219, 35357.46963795597, 33329.33341333208, 33798.831005845794, 33975.49036764401, 34054.48918266178, 34461.827690861384, 36880.44679329758, 37022.8148859254, 37114.814425927696, 37168.0, 37400.0, 33354.166145846546, 33471.66528334783, 33711.662883371835, 34200.31599368049, 34205.65794342025, 23180.76819231786, 25296.494070118126, 25556.74443255618, 25990.740092598833, 26096.73903261019], "value": 17378.384661508786, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [567.452408425869, 568.8094488346404, 570.3013808085094, 583.5011065538964, 584.4097461563821, 21789.237376691817, 22683.77316226837, 22957.966891489883, 22998.770012299872, 23015.309540713806, 26612.334691632717, 27055.0, 27070.0, 27106.186814395598, 27327.316817079532, 6491.09124722538, 6633.170853643294, 6638.070670106189, 6638.4689224861895, 6639.900401493981, 5954.9404505955035, 6476.4818814494965, 6485.935140648604, 6633.805914935309, 6690.866182676344, 6435.066915297279, 6447.323031081739, 6448.80653580391, 6470.450011749007, 6473.77341793035, 6428.421442070189, 6447.2263328400895, 6454.774082907123, 6456.35436456353, 6457.192850893638, 5969.0, 6000.459958603747, 6018.518518518508, 6038.818835434944, 6039.396060393926, 29407.2648183795, 29920.700792992226, 30385.544216836766, 30706.692933070608, 31263.687363126304, 1886.1512319456162, 1912.4931893048354, 1918.7505624268895, 1919.299455698674, 1923.6922092465138, 19319.51701207467, 19924.601507969903, 19939.0, 19976.0, 19995.0, 2192.440927563477, 2245.550889822026, 2251.4821591034156, 2315.7221133463922, 2455.508898220345, 137412.31293843567, 140528.0, 143452.0, 143469.99999999895, 144264.278678607, 14170.787438188436, 14556.126632402089, 14835.480758173411, 14837.0, 14944.775828362583, 22985.310440686808, 22992.540149196924, 23030.884845575663, 23037.88481057584, 23058.538829223322, 10286.228532860065, 10436.660268714091, 10507.264491485676, 10616.840747388795, 10648.467576621139, 9842.852357214648, 9897.901020989842, 9947.900520994843, 10001.099901008944, 10171.745706357253, 7577.734779282699, 8840.646374145092, 9135.168097889675, 9188.770280743034, 9569.090936361035, 2570.910018149356, 2576.9742302576733, 2579.380948572329, 2611.373270415125, 2749.9725002750115, 917.4357769971552, 930.8045310484739, 936.3539157980971, 946.1531928923713, 976.951152442375, 19641.017949102486, 19908.70136947947, 20047.799522004883, 20178.59642807165, 20354.59290814205, 1020.6274709730894, 1021.2638514525337, 1049.333673117576, 1053.7892421515728, 1060.1942523681903, 10601.681949541526, 11093.0, 11217.495212715447, 11240.21318507713, 11303.378314192634, 506804.0, 551884.0, 555893.0, 558506.0, 561621.0, 116197.4190129044, 118220.40889795496, 119453.40273298578, 121664.0, 123591.0, 1203.0285195566935, 1271.1483306184878, 1306.4447609765812, 1318.136620513565, 1326.681596416853, 20914.895425522776, 20915.0, 21082.472938176667, 21299.8935005324, 21420.357389278346, 59141.0, 60798.39201607927, 60859.69570152121, 60863.69568152131, 61733.691331544855, 71605.64197178981, 83489.58255208685, 84129.57935210285, 85795.0, 86270.56864715887, 21687.674684880054, 22273.665895011913, 22511.887440562692, 22522.21172258963, 22537.098516059174, 24393.63409548894, 24523.50952980966, 24602.630960535247, 25296.11463598765, 25329.74670253274, 18608.34870779516, 19032.904835475732, 19479.707804382662, 19532.53505987085, 19532.707009394588, 15785.0, 15812.446564370191, 16339.754903676692, 17099.487015389557, 17559.297628094733, 18696.0, 19489.70765438491, 19546.413607591796, 19671.70492442643, 19712.60574788525, 21176.364709058755, 21203.68194477053, 21294.680579791006, 21343.786562134803, 21358.359249222547, 18980.0, 19044.714329284794, 19050.714239286146, 19167.233310667976, 19251.903740481208], "value": 12503.643592177243, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.8165597644318, 1421.2183299185444, 1422.9715405691886, 1427.5431861804213, 1428.3643778518563, 13808.585742427731, 14445.349959251833, 14447.855521444782, 14451.205183714892, 14467.27663616819, 14665.706685866277, 14777.556673299792, 14833.925830370834, 14853.183074930901, 14856.405743770236, 2615.62073499343, 2683.0743393529297, 2684.4899469100906, 2684.932876678079, 2696.7842572594145, 2533.961990570143, 2717.144099608617, 2722.8502432366213, 2727.9181624551297, 2729.56326987682, 2725.441284536665, 2740.068376751904, 2761.0336382266146, 2783.5268004439195, 2785.665720113589, 2680.2227354067395, 2711.1595405424187, 2718.361185121495, 2722.8774705138217, 2732.8770205340807, 2593.6757905261843, 2602.258356368426, 2610.2430295214554, 2611.151375802872, 2625.5930330798565, 14108.435662573485, 14297.142171469744, 14312.64218394538, 14326.283685815772, 14331.42674293027, 7390.5196162249285, 7546.09446866374, 7768.262015108556, 7787.961060194662, 7836.804079897991, 4879.4876537963355, 4995.100881841304, 5105.412877519096, 5114.539691427789, 5265.420803711589, 1802.4953013156337, 1884.7361369408293, 2245.8203343732625, 2283.680284760135, 2334.6964894563766, 23802.761972380227, 24017.639735403984, 24099.39751506209, 24153.275401737978, 24227.0, 6565.310642382577, 6702.19573651165, 6748.089007983957, 6748.257691653914, 6901.965490172517, 18558.0, 18614.44156675302, 18663.906680466782, 18680.81319186818, 18683.71974420385, 7734.1492435832015, 7795.064592248967, 7803.141654418009, 7814.296713295831, 7841.5687137208, 6781.6609169541325, 6954.547954382949, 6954.721811127498, 7040.471964602675, 7066.470014748915, 5363.973180134152, 5412.5940554458575, 5498.697571633598, 5536.280283563192, 5587.7485513152005, 1665.5669525923347, 1689.5015970288794, 1714.6399256156346, 1722.586579220978, 1731.0392732033843, 838.8867502887155, 858.3690987124459, 875.0374587953328, 886.5921676029099, 889.7997950461095, 6967.686454109577, 6973.163220413583, 6995.4053905417595, 7020.05229294049, 7059.505834591632, 709.6026225313738, 713.3615414204259, 713.7858642407257, 723.8045727653504, 730.2697302697303, 4334.284843000933, 4651.13960398009, 5179.119549676564, 5249.448807875118, 5276.551493123127, 19491.220351185795, 20106.396808095782, 20118.094685739175, 20125.39623811288, 20139.899300503403, 19214.711779323323, 19663.41009769709, 19886.602267954848, 19886.90056549708, 19905.303314383927, 10739.892601073889, 11092.778144437227, 11094.722631934104, 11096.833547496793, 11105.83341249882, 8018.639161237642, 8047.51714897108, 8224.424290299861, 8311.376646751396, 8508.532030738368, 17080.23138958775, 17427.128643568023, 17530.561735956704, 17551.385701500385, 17795.377161799275, 17515.0, 17777.822221777617, 17860.106994650476, 18061.367852125113, 18095.81904180994, 11632.127590430582, 11645.359505227294, 11730.12024098179, 11752.35362055061, 11786.882131178578, 13014.414351354022, 13246.470141194244, 13251.602451926457, 13268.13757105785, 13270.27013514266, 10821.080208182393, 10861.728456788644, 11028.834567481334, 11030.117590592572, 11075.667729968114, 10506.106980906708, 10594.258401911793, 10691.946540267249, 10705.036546710833, 10745.946270268598, 9520.47637379923, 9700.175485083846, 9738.0, 9741.902580974383, 9751.804963900824, 6908.516403851682, 6915.308469153246, 6939.618320992192, 6973.4072603829245, 6982.0, 6938.167419909643, 6943.375096241362, 7045.1898031727005, 7067.960776865792, 7147.928520714934], "value": 6469.4686030829125, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.35453959884376, 497.1379904533661, 510.573670984728, 516.3519782672745, 520.5081198267637, 6117.1249512729, 6143.938560614398, 6223.688815559227, 6240.563160578761, 6276.905846412296, 5767.163761254616, 5978.551608629348, 5998.940010599904, 6000.849978750533, 6068.241469816273, 4812.711237325754, 4813.4223893132785, 4845.933894543195, 4886.462489126193, 4891.241857512091, 4794.736289504076, 4855.393075865517, 4895.0, 4910.582600478963, 4912.771807048238, 4857.684250523723, 4864.659473836833, 4901.436334821487, 4903.583195428375, 4908.803647854082, 4850.223964165734, 4866.223828302679, 4900.754962251873, 4905.803767849317, 4946.9752651236695, 4396.60430561251, 4412.24991751403, 4433.822647094113, 4442.534822838192, 4465.307877279027, 5876.764929402819, 5981.850453738648, 5985.0, 6001.549883758736, 6023.578349515536, 5604.663720176803, 5656.830295091154, 5679.876068162494, 5740.397258287903, 5743.54051675865, 6502.60984340941, 6577.506686998447, 6580.868382632369, 6585.209774827052, 6601.966990165067, 4760.309755085528, 4829.517048295177, 4856.538628830255, 4862.927056094161, 4866.270059491069, 6677.8664426711675, 6851.588904665736, 6865.141857267841, 6942.583444993316, 6957.860842783167, 4926.9014619707405, 4951.876203094879, 4977.477364876636, 4979.576735977483, 4983.651144419856, 4984.3021976923255, 5001.474845141208, 5066.164082926276, 5085.771140298695, 5153.45888681683, 5598.356189038232, 5610.410906854803, 5650.231386620527, 5662.37713851476, 5673.744681489343, 6172.197614310201, 6499.772507962199, 6528.281888992207, 6552.57408268461, 6652.600843949379, 1659.3279721712727, 1667.674803413347, 1705.8208888066822, 1726.2490816494865, 1737.1053907237783, 1035.7255327338262, 1202.6933132051272, 1205.2406983600276, 1219.6280134559045, 1222.3032871263472, 5606.635568688103, 5655.688937108499, 5669.518090962314, 5676.962824888665, 5679.545636349082, 6594.988528498854, 6631.535792494576, 6675.0988616537115, 6686.732530698718, 6785.710714964141, 5732.77068917247, 5763.481286684135, 5772.480476757112, 5807.767689292466, 5857.6820215452035, 3576.499290099336, 3589.838457269429, 3603.675669189733, 3630.7640003399692, 3639.0356555512813, 6567.540272180901, 6587.209534855752, 6590.176227971504, 6614.0740296358545, 6619.86760264792, 6920.7231710731, 6927.411170050501, 6933.930660693329, 6945.0, 6945.93054069463, 6537.8692426152165, 6603.306652801482, 6625.4037136657935, 6635.004749287549, 6651.401373876374, 6751.56114852533, 6762.37702951298, 6771.322867713189, 6779.15260592426, 6799.184097908084, 6676.76631317921, 6720.966395167993, 6734.797956061326, 6745.52781305304, 6769.0, 6495.1556297681, 6533.444657204191, 6536.607803531803, 6537.640429776408, 6601.3728695773825, 4973.875653108701, 4980.103581355246, 4991.376077990251, 5026.220935754964, 5048.242763585492, 6652.800415987334, 6720.899186512304, 6740.966295168493, 6803.285655006153, 6822.8635427292165, 6368.395002474757, 6412.2625898022325, 6441.774537891151, 6558.376954189344, 6574.638394888327, 6443.484521238196, 6469.77355792545, 6478.70845811949, 6500.869982600416, 6575.013747937847, 6323.241211054703, 6358.0, 6385.584936979081, 6449.871002580016, 6473.77341793035, 6265.5226349376535, 6428.164338635947, 6452.032000319676, 6486.318936511787, 6531.673416329259, 5732.7706891723865, 6066.908996365147, 6136.23297087864, 6284.340144284784, 6323.660245709117], "value": 5014.1315578181375, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.401678866765, 899.1952202778517, 928.2388441477991, 929.8605209218621, 931.1014870649822, 18702.812971870295, 19319.13063912124, 19328.130234139462, 19360.128794204276, 22172.33482995513, 21075.578488430223, 21926.122955081777, 21958.0, 21968.670469942965, 22195.223167189153, 1026.743314171459, 1056.3450660590406, 1056.5773690523774, 1056.6301794371948, 1057.5029736024094, 1056.614335767447, 1057.3391630231106, 1060.1677683018831, 1060.3955745225226, 1066.610687099211, 1069.3423544520142, 1118.4966764955802, 1124.8537690100316, 1125.0437128440842, 1125.5329038449033, 1108.5233349659643, 1114.1755101225117, 1114.4929057278919, 1114.554178328667, 1116.8380584815156, 801.887735717, 803.9316658084069, 803.9638216280281, 804.3283857978595, 804.3806269172704, 20080.397588072236, 20202.19191232349, 20234.089465974066, 20346.898265508727, 20930.686039709417, 21025.0, 21611.891940540354, 21745.238916638, 22161.113555457923, 22169.113235470562, 17137.742933856003, 17159.914200428917, 17243.913780431016, 17410.30358785647, 17695.5576110597, 3688.686461650763, 3694.353488139565, 3696.2237930034726, 3712.201876596545, 3729.4965179700666, 125325.37337313347, 125778.0, 129624.0, 130067.34966325203, 130716.3464182673, 16458.75311870299, 17123.486295411156, 17183.3985810496, 17395.91302043482, 17995.640087198182, 30540.694593054228, 31041.379172416426, 31108.0, 31571.684283157334, 31908.202294942814, 7528.736494222676, 7867.134615192324, 8168.673253069811, 8509.914900850912, 8604.440711353742, 4135.193637240769, 4190.811413486401, 4235.089455767024, 4247.766372849523, 4250.447441832542, 5046.596272298208, 5157.819476318386, 5209.791608335624, 5218.947810521846, 5317.175837745156, 993.4138858073627, 998.6005597760953, 1019.6889948565758, 1024.2215915903967, 1027.1782573940818, 618.5979113576121, 651.0787236060921, 704.6582407532343, 708.1643660480579, 725.793148952546, 11803.704907377385, 11866.406679665983, 11867.821982670266, 12052.517899284107, 13681.24753138567, 674.1034424215782, 676.2088356622763, 678.8947713104477, 681.4752640466912, 692.6086760980131, 5363.168708850135, 5519.0, 5559.972200138974, 5574.704031170404, 5722.427757224327, 106758.0, 108701.0, 109511.0, 113220.0, 113504.43247783874, 92795.0, 94556.52721736486, 97272.51363743136, 97520.0, 99238.0, 81146.18853811386, 83648.58175709083, 84429.0, 86245.13754862496, 87654.0, 11977.760444791229, 12705.42825572833, 12817.807732884201, 12858.807117893426, 13061.151025183333, 38564.80717596394, 38848.2230355397, 39375.40936885892, 39790.60209397869, 39966.80016599898, 68664.31335686779, 71282.28717712757, 72058.63970680113, 72558.27441725515, 73167.26832731818, 19352.0, 19659.70510442373, 19714.901425492782, 19904.90047549753, 19939.202431902562, 21818.89090554537, 22056.33830985073, 22203.444913876636, 22645.660315095618, 22729.88635056814, 19292.807071929103, 19297.5175620605, 19338.323158689378, 19371.225150993803, 19394.612107758046, 19770.70343944813, 20042.0, 20289.79710202879, 20385.59228815445, 20669.0, 18669.906650466663, 19529.0, 19937.501562461053, 19993.900030499753, 20154.69767953511, 13941.442342306194, 14136.929315353358, 14203.715925681221, 14205.289735512977, 14266.358013889192, 14253.35859886287, 14446.0, 14521.056131351428, 14608.780868287196, 14609.634759131106], "value": 9403.858456054506, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1519.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 107.0, 100.0, 108.0, 107.0, 109.0, 110.0, 101.0, 108.0, 109.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 142.0, 180.0, 117.0, 147.0, 143.0, 119.0, 175.0, 122.0], "value": 140.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 162.0, 163.0, 163.0, 164.0, 163.0, 164.0, 171.0, 164.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 176.0, 77.0, 75.0, 74.0, 76.0, 149.0, 75.0], "value": 92.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 130.0, 80.0, 79.0, 78.0, 125.0, 79.0, 79.0, 129.0], "value": 93.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 151.0, 151.0, 150.0, 150.0, 149.0, 149.0, 149.0, 149.0], "value": 149.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 98.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 62.0, 53.0, 53.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 55.0, 56.0, 54.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 81.0, 76.0, 75.0, 75.0, 76.0, 76.0, 78.0, 75.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 133.0, 122.0, 130.0, 129.0, 118.0, 126.0, 122.0, 118.0, 170.0], "value": 129.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 181.0, 179.0, 199.0, 177.0, 176.0, 178.0, 180.0, 186.0, 173.0], "value": 181.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 73.0, 66.0, 68.0, 68.0, 66.0, 69.0, 68.0, 67.0], "value": 68.1, "unit": "score"}]}]} 11:21:42 INFO - Return code: 0 11:21:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:21:42 INFO - # TBPL SUCCESS # 11:21:42 INFO - Running post-action listener: _package_coverage_data 11:21:42 INFO - Running post-action listener: _resource_record_post_action 11:21:42 INFO - [mozharness: 2017-08-16 18:21:42.737511Z] Finished run-tests step (success) 11:21:42 INFO - Running post-run listener: _resource_record_post_run 11:21:42 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 7225344; Write bytes: 441708544; Read time: 3428; Write time: 285096 11:21:42 INFO - TinderboxPrint: CPU usage
13.1% 11:21:42 INFO - TinderboxPrint: I/O read bytes / time
7,225,344 / 3,428 11:21:42 INFO - TinderboxPrint: I/O write bytes / time
441,708,544 / 285,096 11:21:42 INFO - TinderboxPrint: CPU idle
8,261.2 (86.8%) 11:21:42 INFO - TinderboxPrint: CPU user
1,197.4 (12.6%) 11:21:42 INFO - TinderboxPrint: Swap in / out
0 / 0 11:21:42 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 198025216; Read time: 184; Write time: 149548 11:21:42 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:21:42 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4853760; Write bytes: 191647744; Read time: 2968; Write time: 72564 11:21:43 INFO - Running post-run listener: _upload_blobber_files 11:21:43 INFO - Blob upload gear active. 11:21:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:21:43 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 11:21:43 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'] 11:21:43 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 11:21:43 INFO - (blobuploader) - INFO - Open directory for files ... 11:21:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:21:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:21:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:21:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:21:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:21:50 INFO - (blobuploader) - INFO - Done attempting. 11:21:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:21:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:21:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:21:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:21:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:21:51 INFO - (blobuploader) - INFO - Done attempting. 11:21:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:21:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:21:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:21:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:21:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:21:52 INFO - (blobuploader) - INFO - Done attempting. 11:21:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:21:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:21:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:21:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:21:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:21:53 INFO - (blobuploader) - INFO - Done attempting. 11:21:53 INFO - (blobuploader) - INFO - Iteration through files over. 11:21:53 INFO - Return code: 0 11:21:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:21:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:21:53 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/689e8ae7132b9cef36e0406560d249d0a065fd00337a645b5aef7bb2fa72c67082d98e1576901814c1c99a766fb93ec27a6536e7097e16f25d610735c2451674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a25450a564adb4afd97a57c6940c81331e3f000cb7020f08c49c9914d7607674f5fe74bd5962b7f60c7b0b7555089946ac7f347cc7bef9c952b9a90a7a1d95a", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c11176d890dbc829a803c7baf2a6aa224430f3a75924bf1f0298f3fb90bb66284aa6ed1682eb6fa03effb0764d8915d2057c2ef772c91e8eb10943ca54316cb7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8d4cc8f1cfa35bf1cdd43dd56ee1c157cc1cfc6dabb9a2c9ed6ca7d1c14244ca24b22eaf5938b622367698103613468112a085a16d3579e7592ea6659aff6615"} 11:21:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:21:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:21:53 INFO - Contents: 11:21:53 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/689e8ae7132b9cef36e0406560d249d0a065fd00337a645b5aef7bb2fa72c67082d98e1576901814c1c99a766fb93ec27a6536e7097e16f25d610735c2451674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a25450a564adb4afd97a57c6940c81331e3f000cb7020f08c49c9914d7607674f5fe74bd5962b7f60c7b0b7555089946ac7f347cc7bef9c952b9a90a7a1d95a", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c11176d890dbc829a803c7baf2a6aa224430f3a75924bf1f0298f3fb90bb66284aa6ed1682eb6fa03effb0764d8915d2057c2ef772c91e8eb10943ca54316cb7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8d4cc8f1cfa35bf1cdd43dd56ee1c157cc1cfc6dabb9a2c9ed6ca7d1c14244ca24b22eaf5938b622367698103613468112a085a16d3579e7592ea6659aff6615"} 11:21:53 INFO - Running post-run listener: copy_logs_to_upload_dir 11:21:53 INFO - Copying logs to upload dir... 11:21:53 INFO - mkdir: /builds/slave/test/build/upload/logs 11:21:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.843311 ========= master_lag: -0.30 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-08-16 11:21:52.887882) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 11:21:52.895452) ========= 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=438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245 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/689e8ae7132b9cef36e0406560d249d0a065fd00337a645b5aef7bb2fa72c67082d98e1576901814c1c99a766fb93ec27a6536e7097e16f25d610735c2451674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a25450a564adb4afd97a57c6940c81331e3f000cb7020f08c49c9914d7607674f5fe74bd5962b7f60c7b0b7555089946ac7f347cc7bef9c952b9a90a7a1d95a", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c11176d890dbc829a803c7baf2a6aa224430f3a75924bf1f0298f3fb90bb66284aa6ed1682eb6fa03effb0764d8915d2057c2ef772c91e8eb10943ca54316cb7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8d4cc8f1cfa35bf1cdd43dd56ee1c157cc1cfc6dabb9a2c9ed6ca7d1c14244ca24b22eaf5938b622367698103613468112a085a16d3579e7592ea6659aff6615"} build_url:https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011220 build_url: 'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/689e8ae7132b9cef36e0406560d249d0a065fd00337a645b5aef7bb2fa72c67082d98e1576901814c1c99a766fb93ec27a6536e7097e16f25d610735c2451674", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a25450a564adb4afd97a57c6940c81331e3f000cb7020f08c49c9914d7607674f5fe74bd5962b7f60c7b0b7555089946ac7f347cc7bef9c952b9a90a7a1d95a", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c11176d890dbc829a803c7baf2a6aa224430f3a75924bf1f0298f3fb90bb66284aa6ed1682eb6fa03effb0764d8915d2057c2ef772c91e8eb10943ca54316cb7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8d4cc8f1cfa35bf1cdd43dd56ee1c157cc1cfc6dabb9a2c9ed6ca7d1c14244ca24b22eaf5938b622367698103613468112a085a16d3579e7592ea6659aff6615"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 11:21:52.921061) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:21:52.924359) ========= 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=438111f3d31ce799eff2119d000001bc-1502906449.55402-828997245 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004367 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:21:53.006859) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 11:21:53.010172) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 11:21:53.010443) ========= ========= Total master_lag: -0.14 =========