builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-027 starttime: 1502906536.71 results: success (0) revision: 07ce8c96222d533fc89c02802143d35d7c351f9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:16.707340) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:16.707791) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:16.708063) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:16.724087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:16.724461) ========= 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=1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-16 11:02:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2017-08-16 11:02:17 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107255 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:16.846854) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:16.850194) ========= 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=1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015861 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:16.900017) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-08-16 11:02:16.903368) ========= 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=1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-16 11:02:17,364 truncating revision to first 12 chars 2017-08-16 11:02:17,364 Setting DEBUG logging. 2017-08-16 11:02:17,364 attempt 1/10 2017-08-16 11:02:17,364 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:02:19,034 unpacking tar archive at: mozilla-central-07ce8c96222d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.003736 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-08-16 11:02:19.020324) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:19.020721) ========= script_repo_revision: 07ce8c96222d533fc89c02802143d35d7c351f9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:19.021144) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:19.021413) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-16 11:02:19.037192) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-08-16 11:02:19.037579) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:02:19 INFO - MultiFileLogger online at 20170816 11:02:19 in /builds/slave/test 11:02:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:02:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:02:19 INFO - {'append_to_log': False, 11:02:19 INFO - 'base_work_dir': '/builds/slave/test', 11:02:19 INFO - 'blob_upload_branch': 'Firefox', 11:02:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:02:19 INFO - 'branch': 'Firefox', 11:02:19 INFO - 'buildbot_json_path': 'buildprops.json', 11:02:19 INFO - 'code_coverage': False, 11:02:19 INFO - 'config_files': ('talos/linux_config.py',), 11:02:19 INFO - 'default_actions': ('clobber', 11:02:19 INFO - 'read-buildbot-config', 11:02:19 INFO - 'download-and-extract', 11:02:19 INFO - 'populate-webroot', 11:02:19 INFO - 'create-virtualenv', 11:02:19 INFO - 'install', 11:02:19 INFO - 'setup-mitmproxy', 11:02:19 INFO - 'run-tests'), 11:02:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:02:19 INFO - 'disable_ccov_upload': False, 11:02:19 INFO - 'download_minidump_stackwalk': True, 11:02:19 INFO - 'download_symbols': 'ondemand', 11:02:19 INFO - 'enable_stylo': False, 11:02:19 INFO - 'enable_webrender': False, 11:02:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:02:19 INFO - 'tooltool.py': '/tools/tooltool.py', 11:02:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:02:19 INFO - '/tools/misc-python/virtualenv.py')}, 11:02:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:02:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:02:19 INFO - 'gecko_profile': False, 11:02:19 INFO - 'gecko_profile_interval': 0, 11:02:19 INFO - 'installer_path': 'installer.exe', 11:02:19 INFO - 'log_level': 'info', 11:02:19 INFO - 'log_name': 'talos', 11:02:19 INFO - 'log_to_console': True, 11:02:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:02:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:02:19 INFO - 'opt_config_files': (), 11:02:19 INFO - 'pip_index': False, 11:02:19 INFO - 'suite': 'dromaeojs-e10s', 11:02:19 INFO - 'system_bits': '32', 11:02:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:02:19 INFO - 'title': 'talos-linux64-ix-027', 11:02:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:02:19 INFO - 'use_talos_json': True, 11:02:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:02:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:02:19 INFO - 'work_dir': 'build'} 11:02:19 INFO - [mozharness: 2017-08-16 18:02:19.600528Z] Running clobber step. 11:02:19 INFO - Running pre-action listener: _resource_record_pre_action 11:02:19 INFO - Running main action method: clobber 11:02:19 INFO - rmtree: /builds/slave/test/build 11:02:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:02:20 INFO - Running post-action listener: _resource_record_post_action 11:02:20 INFO - [mozharness: 2017-08-16 18:02:20.645483Z] Finished clobber step (success) 11:02:20 INFO - [mozharness: 2017-08-16 18:02:20.645587Z] Running read-buildbot-config step. 11:02:20 INFO - Running pre-action listener: _resource_record_pre_action 11:02:20 INFO - Running main action method: read_buildbot_config 11:02:20 INFO - Using buildbot properties: 11:02:20 INFO - { 11:02:20 INFO - "project": "", 11:02:20 INFO - "product": "firefox", 11:02:20 INFO - "who": "ffxbld@noreply.mozilla.org", 11:02:20 INFO - "installer_path": "public/build/target.tar.bz2", 11:02:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 11:02:20 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:02:20 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 11:02:20 INFO - "stage_platform": "linux64-stylo", 11:02:20 INFO - "basedir": "/builds/slave/test", 11:02:20 INFO - "buildnumber": 88, 11:02:20 INFO - "platform": "ubuntu64_hw_stylo", 11:02:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:02:20 INFO - "slavebuilddir": "test", 11:02:20 INFO - "taskId": "Gpxjk_n2QDmVX4-QbAerig", 11:02:20 INFO - "branch": "mozilla-central", 11:02:20 INFO - "script_repo_revision": "production", 11:02:20 INFO - "revision": "07ce8c96222d533fc89c02802143d35d7c351f9c", 11:02:20 INFO - "slavename": "talos-linux64-ix-027", 11:02:20 INFO - "repo_path": "mozilla-central" 11:02:20 INFO - } 11:02:20 INFO - Finding installer, test and symbols from parent task. 11:02:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gpxjk_n2QDmVX4-QbAerig'}, attempt #1 11:02:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gpxjk_n2QDmVX4-QbAerig'}, attempt #1 11:02:21 INFO - Task dependencies: YbqntiLNQ_uKrat-Wa379w 11:02:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w'}, attempt #1 11:02:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 11:02:22 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.test_packages.json 11:02:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.crashreporter-symbols.zip 11:02:22 INFO - Running post-action listener: _resource_record_post_action 11:02:22 INFO - [mozharness: 2017-08-16 18:02:22.429580Z] Finished read-buildbot-config step (success) 11:02:22 INFO - [mozharness: 2017-08-16 18:02:22.429804Z] Running download-and-extract step. 11:02:22 INFO - Running pre-action listener: _resource_record_pre_action 11:02:22 INFO - Running main action method: download_and_extract 11:02:22 INFO - mkdir: /builds/slave/test/build/tests 11:02:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:22 INFO - trying https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.test_packages.json 11:02:22 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:02:22 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:02:22 INFO - Downloaded 989 bytes. 11:02:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:02:22 INFO - Using the following test package requirements: 11:02:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:02:22 INFO - u'common': [u'target.common.tests.zip'], 11:02:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:02:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:02:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:02:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:02:22 INFO - u'mozbase': [u'target.common.tests.zip'], 11:02:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:02:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:02:22 INFO - u'web-platform': [u'target.common.tests.zip', 11:02:22 INFO - u'target.web-platform.tests.tar.gz'], 11:02:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:02:22 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:02: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.common.tests.zip 11:02: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.common.tests.zip'}, attempt #1 11:02:22 INFO - Fetch https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.common.tests.zip into memory 11:02:24 INFO - Content-Length response header: 54003849 11:02:24 INFO - Bytes received: 54003849 11:02:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:02:34 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:02:34 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:02:34 INFO - Fetch https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.common.tests.zip into memory 11:02:35 INFO - Content-Length response header: 54003849 11:02:35 INFO - Bytes received: 54003849 11:02:47 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:02:47 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:02:47 INFO - Fetch https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.talos.tests.zip into memory 11:02:48 INFO - Content-Length response header: 14496050 11:02:48 INFO - Bytes received: 14496050 11:02: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:02:48 INFO - trying https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 11:02:48 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:02:48 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:02:50 INFO - Downloaded 61902147 bytes. 11:02:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 11:02:50 INFO - mkdir: /builds/slave/test/properties 11:02:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:02:50 INFO - Writing to file /builds/slave/test/properties/build_url 11:02:50 INFO - Contents: 11:02:50 INFO - build_url:https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.tar.bz2 11:02:50 INFO - Running post-action listener: _resource_record_post_action 11:02:50 INFO - Running post-action listener: set_extra_try_arguments 11:02:50 INFO - [mozharness: 2017-08-16 18:02:50.040480Z] Finished download-and-extract step (success) 11:02:50 INFO - [mozharness: 2017-08-16 18:02:50.040579Z] Running populate-webroot step. 11:02:50 INFO - Running pre-action listener: _resource_record_pre_action 11:02:50 INFO - Running main action method: populate_webroot 11:02:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:02:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:02:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:02:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:02:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:02:50 INFO - 'tests': ['damp', 'tps']}, 11:02:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:02:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:02:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:02:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:02:50 INFO - 'other-e10s': {'tests': ['a11yr', 11:02:50 INFO - 'ts_paint', 11:02:50 INFO - 'tpaint', 11:02:50 INFO - 'sessionrestore', 11:02:50 INFO - 'sessionrestore_many_windows', 11:02:50 INFO - 'sessionrestore_no_auto_restore', 11:02:50 INFO - 'tabpaint']}, 11:02:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:02:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:02:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:02:50 INFO - 'tsvgr_opacity', 11:02:50 INFO - 'tart', 11:02:50 INFO - 'tscrollx', 11:02:50 INFO - 'tsvg_static']}, 11:02:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:02:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:02:50 INFO - 'talos_options': ['--mitmproxy', 11:02:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:02:50 INFO - '--firstNonBlankPaint'], 11:02:50 INFO - 'tests': ['tp6_google', 11:02:50 INFO - 'tp6_youtube', 11:02:50 INFO - 'tp6_amazon', 11:02:50 INFO - 'tp6_facebook']}, 11:02:50 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:02:50 INFO - 'talos_options': ['--stylo', 11:02:50 INFO - '--mitmproxy', 11:02:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:02:50 INFO - '--firstNonBlankPaint'], 11:02:50 INFO - 'tests': ['tp6_google', 11:02:50 INFO - 'tp6_youtube', 11:02:50 INFO - 'tp6_amazon', 11:02:50 INFO - 'tp6_facebook']}, 11:02:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:02:50 INFO - 'talos_options': ['--stylo', 11:02:50 INFO - '--stylo-threads=1', 11:02:50 INFO - '--mitmproxy', 11:02:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:02:50 INFO - '--firstNonBlankPaint'], 11:02:50 INFO - 'tests': ['tp6_google', 11:02:50 INFO - 'tp6_youtube', 11:02:50 INFO - 'tp6_amazon', 11:02:50 INFO - 'tp6_facebook']}, 11:02:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:02:50 INFO - 'talos_options': ['--xperf_path', 11:02:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:02:50 INFO - 'tests': ['tp5n']}}} 11:02:50 INFO - Running post-action listener: _resource_record_post_action 11:02:50 INFO - [mozharness: 2017-08-16 18:02:50.049068Z] Finished populate-webroot step (success) 11:02:50 INFO - [mozharness: 2017-08-16 18:02:50.049158Z] Running create-virtualenv step. 11:02:50 INFO - Running pre-action listener: _resource_record_pre_action 11:02:50 INFO - Running main action method: create_virtualenv 11:02:50 INFO - Creating virtualenv /builds/slave/test/build/venv 11:02:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:02:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:02:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:02:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:02:50 INFO - Using real prefix '/usr' 11:02:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:02:50 INFO - Installing distribute.............................................................................................................................................................................................done. 11:02:52 INFO - Installing pip.................done. 11:02:52 INFO - Return code: 0 11:02:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:02:52 INFO - Reading from file tmpfile_stdout 11:02:52 INFO - Output received: 11:02:52 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:02:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:02:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:02:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:02:52 INFO - Using env: {'DISPLAY': ':0', 11:02:52 INFO - 'HOME': '/home/cltbld', 11:02:52 INFO - 'LANG': 'en_US.UTF-8', 11:02:52 INFO - 'LANGUAGE': 'en_US:en', 11:02:52 INFO - 'LOGNAME': 'cltbld', 11:02:52 INFO - 'MAIL': '/var/mail/cltbld', 11:02:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:52 INFO - 'MOZ_NO_REMOTE': '1', 11:02:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:02:52 INFO - 'NO_EM_RESTART': '1', 11:02:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:02:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:52 INFO - 'PWD': '/builds/slave/test', 11:02:52 INFO - 'SHELL': '/bin/bash', 11:02:52 INFO - 'SHLVL': '1', 11:02:52 INFO - 'TERM': 'linux', 11:02:52 INFO - 'TMOUT': '86400', 11:02:52 INFO - 'USER': 'cltbld', 11:02:52 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:02:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:52 INFO - '_': '/tools/buildbot/bin/python'} 11:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:02:53 INFO - Cleaning up... 11:02:53 INFO - Return code: 0 11:02:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:02:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:02:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:02:53 INFO - Using env: {'DISPLAY': ':0', 11:02:53 INFO - 'HOME': '/home/cltbld', 11:02:53 INFO - 'LANG': 'en_US.UTF-8', 11:02:53 INFO - 'LANGUAGE': 'en_US:en', 11:02:53 INFO - 'LOGNAME': 'cltbld', 11:02:53 INFO - 'MAIL': '/var/mail/cltbld', 11:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:53 INFO - 'MOZ_NO_REMOTE': '1', 11:02:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:02:53 INFO - 'NO_EM_RESTART': '1', 11:02:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:53 INFO - 'PWD': '/builds/slave/test', 11:02:53 INFO - 'SHELL': '/bin/bash', 11:02:53 INFO - 'SHLVL': '1', 11:02:53 INFO - 'TERM': 'linux', 11:02:53 INFO - 'TMOUT': '86400', 11:02:53 INFO - 'USER': 'cltbld', 11:02:53 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:53 INFO - '_': '/tools/buildbot/bin/python'} 11:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:53 INFO - Downloading/unpacking psutil>=3.1.1 11:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:02:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:02:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:02:54 INFO - Installing collected packages: psutil 11:02:54 INFO - Running setup.py install for psutil 11:02:54 INFO - building 'psutil._psutil_linux' extension 11:02:54 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:02:54 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:02:54 INFO - building 'psutil._psutil_posix' extension 11:02:54 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:02:54 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:02:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:02:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:02:55 INFO - Successfully installed psutil 11:02:55 INFO - Cleaning up... 11:02:55 INFO - Return code: 0 11:02:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:02: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:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02: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:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:55 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:02:55 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:02:55 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:02:55 INFO - Using env: {'DISPLAY': ':0', 11:02:55 INFO - 'HOME': '/home/cltbld', 11:02:55 INFO - 'LANG': 'en_US.UTF-8', 11:02:55 INFO - 'LANGUAGE': 'en_US:en', 11:02:55 INFO - 'LOGNAME': 'cltbld', 11:02:55 INFO - 'MAIL': '/var/mail/cltbld', 11:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:55 INFO - 'MOZ_NO_REMOTE': '1', 11:02:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:02:55 INFO - 'NO_EM_RESTART': '1', 11:02:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:55 INFO - 'PWD': '/builds/slave/test', 11:02:55 INFO - 'SHELL': '/bin/bash', 11:02:55 INFO - 'SHLVL': '1', 11:02:55 INFO - 'TERM': 'linux', 11:02:55 INFO - 'TMOUT': '86400', 11:02:55 INFO - 'USER': 'cltbld', 11:02:55 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:55 INFO - '_': '/tools/buildbot/bin/python'} 11:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:02:55 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:02:55 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:02:55 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:02:55 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:02:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:02:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:02:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:02:56 INFO - Installing collected packages: mozsystemmonitor 11:02:56 INFO - Running setup.py install for mozsystemmonitor 11:02:56 INFO - Successfully installed mozsystemmonitor 11:02:56 INFO - Cleaning up... 11:02:56 INFO - Return code: 0 11:02:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:02:56 INFO - Using env: {'DISPLAY': ':0', 11:02:56 INFO - 'HOME': '/home/cltbld', 11:02:56 INFO - 'LANG': 'en_US.UTF-8', 11:02:56 INFO - 'LANGUAGE': 'en_US:en', 11:02:56 INFO - 'LOGNAME': 'cltbld', 11:02:56 INFO - 'MAIL': '/var/mail/cltbld', 11:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:56 INFO - 'MOZ_NO_REMOTE': '1', 11:02:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:02:56 INFO - 'NO_EM_RESTART': '1', 11:02:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:56 INFO - 'PWD': '/builds/slave/test', 11:02:56 INFO - 'SHELL': '/bin/bash', 11:02:56 INFO - 'SHLVL': '1', 11:02:56 INFO - 'TERM': 'linux', 11:02:56 INFO - 'TMOUT': '86400', 11:02:56 INFO - 'USER': 'cltbld', 11:02:56 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:56 INFO - '_': '/tools/buildbot/bin/python'} 11:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:56 INFO - Downloading/unpacking jsonschema==2.5.1 11:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:02:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:57 INFO - Downloading functools32-3.2.3-2.tar.gz 11:02:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:02:58 INFO - warning: no files found matching '*.txt' 11:02:58 INFO - no previously-included directories found matching 'build' 11:02:58 INFO - no previously-included directories found matching 'dist' 11:02:58 INFO - no previously-included directories found matching '.git*' 11:02:58 INFO - Installing collected packages: jsonschema, functools32 11:02:58 INFO - Running setup.py install for functools32 11:02:58 INFO - warning: no files found matching '*.txt' 11:02:58 INFO - no previously-included directories found matching 'build' 11:02:58 INFO - no previously-included directories found matching 'dist' 11:02:58 INFO - no previously-included directories found matching '.git*' 11:02:58 INFO - Successfully installed jsonschema functools32 11:02:58 INFO - Cleaning up... 11:02:58 INFO - Return code: 0 11:02:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:02:58 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:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:58 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:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:58 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:02:58 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:02:58 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:02:58 INFO - Using env: {'DISPLAY': ':0', 11:02:58 INFO - 'HOME': '/home/cltbld', 11:02:58 INFO - 'LANG': 'en_US.UTF-8', 11:02:58 INFO - 'LANGUAGE': 'en_US:en', 11:02:58 INFO - 'LOGNAME': 'cltbld', 11:02:58 INFO - 'MAIL': '/var/mail/cltbld', 11:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:58 INFO - 'MOZ_NO_REMOTE': '1', 11:02:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:02:58 INFO - 'NO_EM_RESTART': '1', 11:02:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:58 INFO - 'PWD': '/builds/slave/test', 11:02:58 INFO - 'SHELL': '/bin/bash', 11:02:58 INFO - 'SHLVL': '1', 11:02:58 INFO - 'TERM': 'linux', 11:02:58 INFO - 'TMOUT': '86400', 11:02:58 INFO - 'USER': 'cltbld', 11:02:58 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:58 INFO - '_': '/tools/buildbot/bin/python'} 11:02:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:02:58 INFO - Cleaning up... 11:02:58 INFO - Return code: 0 11:02:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:02:58 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:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:58 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:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:58 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:02:58 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:02:58 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:02:58 INFO - Using env: {'DISPLAY': ':0', 11:02:58 INFO - 'HOME': '/home/cltbld', 11:02:58 INFO - 'LANG': 'en_US.UTF-8', 11:02:58 INFO - 'LANGUAGE': 'en_US:en', 11:02:58 INFO - 'LOGNAME': 'cltbld', 11:02:58 INFO - 'MAIL': '/var/mail/cltbld', 11:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:58 INFO - 'MOZ_NO_REMOTE': '1', 11:02:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:02:58 INFO - 'NO_EM_RESTART': '1', 11:02:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:58 INFO - 'PWD': '/builds/slave/test', 11:02:58 INFO - 'SHELL': '/bin/bash', 11:02:58 INFO - 'SHLVL': '1', 11:02:58 INFO - 'TERM': 'linux', 11:02:58 INFO - 'TMOUT': '86400', 11:02:58 INFO - 'USER': 'cltbld', 11:02:58 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:58 INFO - '_': '/tools/buildbot/bin/python'} 11:02:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:58 INFO - Downloading/unpacking blobuploader==1.2.4 11:02:58 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:02:58 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:02:58 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:02:58 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:02:59 INFO - Downloading blobuploader-1.2.4.tar.gz 11:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:02:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:03:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:03:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:03:00 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:03:00 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:03:00 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:03:00 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:03:00 INFO - Downloading docopt-0.6.1.tar.gz 11:03:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:03:00 INFO - Installing collected packages: blobuploader, requests, docopt 11:03:00 INFO - Running setup.py install for blobuploader 11:03:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:03:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:03:00 INFO - Running setup.py install for requests 11:03:00 INFO - Running setup.py install for docopt 11:03:00 INFO - Successfully installed blobuploader requests docopt 11:03:00 INFO - Cleaning up... 11:03:00 INFO - Return code: 0 11:03:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:00 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:03:00 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:03:00 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:03:00 INFO - Using env: {'DISPLAY': ':0', 11:03:00 INFO - 'HOME': '/home/cltbld', 11:03:00 INFO - 'LANG': 'en_US.UTF-8', 11:03:00 INFO - 'LANGUAGE': 'en_US:en', 11:03:00 INFO - 'LOGNAME': 'cltbld', 11:03:00 INFO - 'MAIL': '/var/mail/cltbld', 11:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:00 INFO - 'MOZ_NO_REMOTE': '1', 11:03:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:00 INFO - 'NO_EM_RESTART': '1', 11:03:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:00 INFO - 'PWD': '/builds/slave/test', 11:03:00 INFO - 'SHELL': '/bin/bash', 11:03:00 INFO - 'SHLVL': '1', 11:03:00 INFO - 'TERM': 'linux', 11:03:00 INFO - 'TMOUT': '86400', 11:03:00 INFO - 'USER': 'cltbld', 11:03:00 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:00 INFO - '_': '/tools/buildbot/bin/python'} 11:03:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:03:01 INFO - Running setup.py (path:/tmp/pip-H3ywkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:03:01 INFO - Running setup.py (path:/tmp/pip-Z0WdKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:03:01 INFO - Running setup.py (path:/tmp/pip-B1eMU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:03:01 INFO - Running setup.py (path:/tmp/pip-cpzPIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:03:01 INFO - Running setup.py (path:/tmp/pip-GT5CWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:03:01 INFO - Running setup.py (path:/tmp/pip-MeAW1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:03:01 INFO - Running setup.py (path:/tmp/pip-8viCJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:03:01 INFO - Running setup.py (path:/tmp/pip-PEiuBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:03:01 INFO - Running setup.py (path:/tmp/pip-0hsl21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:03:01 INFO - Running setup.py (path:/tmp/pip-T8KVEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:03:01 INFO - Running setup.py (path:/tmp/pip-i__HZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:03:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:03:01 INFO - Running setup.py (path:/tmp/pip-fvDCo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:03:02 INFO - Running setup.py (path:/tmp/pip-oCS459-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:03:02 INFO - Running setup.py (path:/tmp/pip-6yi_Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:02 INFO - Running setup.py (path:/tmp/pip-qPUYZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:03:02 INFO - Running setup.py (path:/tmp/pip-XrtljM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:03:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:03:02 INFO - Running setup.py (path:/tmp/pip-OWaEOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:03:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:03:02 INFO - Running setup.py install for manifestparser 11:03:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:03:02 INFO - Running setup.py install for mozcrash 11:03:02 INFO - Running setup.py install for mozdebug 11:03:02 INFO - Running setup.py install for mozdevice 11:03:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:03:02 INFO - Running setup.py install for mozfile 11:03:02 INFO - Running setup.py install for mozhttpd 11:03:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:03:02 INFO - Running setup.py install for mozinfo 11:03:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Running setup.py install for mozInstall 11:03:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Running setup.py install for mozleak 11:03:03 INFO - Running setup.py install for mozlog 11:03:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Running setup.py install for moznetwork 11:03:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Running setup.py install for mozprocess 11:03:03 INFO - Running setup.py install for mozprofile 11:03:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Running setup.py install for mozrunner 11:03:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:03:03 INFO - Running setup.py install for mozscreenshot 11:03:03 INFO - Running setup.py install for moztest 11:03:04 INFO - Running setup.py install for mozversion 11:03:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:03:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:03:04 INFO - Cleaning up... 11:03:04 INFO - Return code: 0 11:03:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03:04 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:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:04 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:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:04 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:03:04 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:03:04 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:03:04 INFO - Using env: {'DISPLAY': ':0', 11:03:04 INFO - 'HOME': '/home/cltbld', 11:03:04 INFO - 'LANG': 'en_US.UTF-8', 11:03:04 INFO - 'LANGUAGE': 'en_US:en', 11:03:04 INFO - 'LOGNAME': 'cltbld', 11:03:04 INFO - 'MAIL': '/var/mail/cltbld', 11:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:04 INFO - 'MOZ_NO_REMOTE': '1', 11:03:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:04 INFO - 'NO_EM_RESTART': '1', 11:03:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:04 INFO - 'PWD': '/builds/slave/test', 11:03:04 INFO - 'SHELL': '/bin/bash', 11:03:04 INFO - 'SHLVL': '1', 11:03:04 INFO - 'TERM': 'linux', 11:03:04 INFO - 'TMOUT': '86400', 11:03:04 INFO - 'USER': 'cltbld', 11:03:04 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:04 INFO - '_': '/tools/buildbot/bin/python'} 11:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:03:04 INFO - Running setup.py (path:/tmp/pip-B7J_i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:03:04 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:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:03:04 INFO - Running setup.py (path:/tmp/pip-juBLU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:03:04 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:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:03:04 INFO - Running setup.py (path:/tmp/pip-apUsxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:03:04 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:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:03:04 INFO - Running setup.py (path:/tmp/pip-PKcr43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:03:04 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:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:03:04 INFO - Running setup.py (path:/tmp/pip-7eOKhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:03:04 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:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:03:04 INFO - Running setup.py (path:/tmp/pip-T2hV_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:03:04 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:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:03:04 INFO - Running setup.py (path:/tmp/pip-ptgCAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:03:04 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:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:03:04 INFO - Running setup.py (path:/tmp/pip-WhBzzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:03:04 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:03:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:03:04 INFO - Running setup.py (path:/tmp/pip-bsgH6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:03:05 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:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:03:05 INFO - Running setup.py (path:/tmp/pip-zD_SPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:03:05 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:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:03:05 INFO - Running setup.py (path:/tmp/pip-mAzowt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:03:05 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:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:03:05 INFO - Running setup.py (path:/tmp/pip-4XYyWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:03:05 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:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:03:05 INFO - Running setup.py (path:/tmp/pip-EGl8LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:03:05 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:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:03:05 INFO - Running setup.py (path:/tmp/pip-f8nCQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:03:05 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:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:05 INFO - Running setup.py (path:/tmp/pip-Br6unT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:03:05 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:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:03:05 INFO - Running setup.py (path:/tmp/pip-ALmrYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:03:05 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:03:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:03:05 INFO - Running setup.py (path:/tmp/pip-NinR5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:03:05 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:03:05 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:03:05 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:03:05 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:03:05 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:03:05 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:03:05 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:03:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:03:05 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:03:05 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:03:05 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:03:05 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:03:06 INFO - Downloading blessings-1.6.tar.gz 11:03:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:03:06 INFO - Installing collected packages: blessings 11:03:06 INFO - Running setup.py install for blessings 11:03:06 INFO - Successfully installed blessings 11:03:06 INFO - Cleaning up... 11:03:06 INFO - Return code: 0 11:03:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:03:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:03:07 INFO - Reading from file tmpfile_stdout 11:03:07 INFO - Current package versions: 11:03:07 INFO - argparse == 1.2.1 11:03:07 INFO - blessings == 1.6 11:03:07 INFO - blobuploader == 1.2.4 11:03:07 INFO - docopt == 0.6.1 11:03:07 INFO - functools32 == 3.2.3-2 11:03:07 INFO - jsonschema == 2.5.1 11:03:07 INFO - manifestparser == 1.1 11:03:07 INFO - mozInstall == 1.13 11:03:07 INFO - mozcrash == 1.0 11:03:07 INFO - mozdebug == 0.1 11:03:07 INFO - mozdevice == 0.51 11:03:07 INFO - mozfile == 1.2 11:03:07 INFO - mozhttpd == 0.7 11:03:07 INFO - mozinfo == 0.10 11:03:07 INFO - mozleak == 0.1 11:03:07 INFO - mozlog == 3.5 11:03:07 INFO - moznetwork == 0.27 11:03:07 INFO - mozprocess == 0.25 11:03:07 INFO - mozprofile == 0.28 11:03:07 INFO - mozrunner == 6.13 11:03:07 INFO - mozscreenshot == 0.1 11:03:07 INFO - mozsystemmonitor == 0.3 11:03:07 INFO - moztest == 0.8 11:03:07 INFO - mozversion == 1.4 11:03:07 INFO - psutil == 3.1.1 11:03:07 INFO - requests == 1.2.3 11:03:07 INFO - wsgiref == 0.1.2 11:03:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:03: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:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03: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:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:07 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:03:07 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:03:07 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:03:07 INFO - Using env: {'DISPLAY': ':0', 11:03:07 INFO - 'HOME': '/home/cltbld', 11:03:07 INFO - 'LANG': 'en_US.UTF-8', 11:03:07 INFO - 'LANGUAGE': 'en_US:en', 11:03:07 INFO - 'LOGNAME': 'cltbld', 11:03:07 INFO - 'MAIL': '/var/mail/cltbld', 11:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:07 INFO - 'MOZ_NO_REMOTE': '1', 11:03:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:07 INFO - 'NO_EM_RESTART': '1', 11:03:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:07 INFO - 'PWD': '/builds/slave/test', 11:03:07 INFO - 'SHELL': '/bin/bash', 11:03:07 INFO - 'SHLVL': '1', 11:03:07 INFO - 'TERM': 'linux', 11:03:07 INFO - 'TMOUT': '86400', 11:03:07 INFO - 'USER': 'cltbld', 11:03:07 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:07 INFO - '_': '/tools/buildbot/bin/python'} 11:03:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:07 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:03:07 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:03:07 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:03:07 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:03:07 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:03:07 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:03:07 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:03:07 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:03:07 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:03:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:03:07 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:03:07 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:03:07 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:03:07 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:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:03:08 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:03:08 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:03:08 INFO - Installing collected packages: simplejson 11:03:08 INFO - Running setup.py install for simplejson 11:03:08 INFO - building 'simplejson._speedups' extension 11:03:08 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:03:09 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:03:09 INFO - Successfully installed simplejson 11:03:09 INFO - Cleaning up... 11:03:09 INFO - Return code: 0 11:03:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:03:09 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:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:03:09 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:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:03:09 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 0x20b11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21b5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2358ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, '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': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', '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:03:09 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:03:09 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:03:09 INFO - Using env: {'DISPLAY': ':0', 11:03:09 INFO - 'HOME': '/home/cltbld', 11:03:09 INFO - 'LANG': 'en_US.UTF-8', 11:03:09 INFO - 'LANGUAGE': 'en_US:en', 11:03:09 INFO - 'LOGNAME': 'cltbld', 11:03:09 INFO - 'MAIL': '/var/mail/cltbld', 11:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:09 INFO - 'MOZ_NO_REMOTE': '1', 11:03:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:09 INFO - 'NO_EM_RESTART': '1', 11:03:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:09 INFO - 'PWD': '/builds/slave/test', 11:03:09 INFO - 'SHELL': '/bin/bash', 11:03:09 INFO - 'SHLVL': '1', 11:03:09 INFO - 'TERM': 'linux', 11:03:09 INFO - 'TMOUT': '86400', 11:03:09 INFO - 'USER': 'cltbld', 11:03:09 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:09 INFO - '_': '/tools/buildbot/bin/python'} 11:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:03:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:03:09 INFO - Cleaning up... 11:03:09 INFO - Return code: 0 11:03:09 INFO - Running post-action listener: _resource_record_post_action 11:03:09 INFO - Running post-action listener: _start_resource_monitoring 11:03:09 INFO - Starting resource monitoring. 11:03:09 INFO - [mozharness: 2017-08-16 18:03:09.890152Z] Finished create-virtualenv step (success) 11:03:09 INFO - [mozharness: 2017-08-16 18:03:09.890668Z] Running install step. 11:03:09 INFO - Running pre-action listener: _resource_record_pre_action 11:03:09 INFO - Running main action method: install 11:03:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:03:10 INFO - Reading from file tmpfile_stdout 11:03:10 INFO - Detecting whether we're running mozinstall >=1.0... 11:03:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:03:10 INFO - Reading from file tmpfile_stdout 11:03:10 INFO - Output received: 11:03:10 INFO - Usage: mozinstall [options] installer 11:03:10 INFO - Options: 11:03:10 INFO - -h, --help show this help message and exit 11:03:10 INFO - -d DEST, --destination=DEST 11:03:10 INFO - Directory to install application into. [default: 11:03:10 INFO - "/builds/slave/test"] 11:03:10 INFO - --app=APP Application being installed. [default: firefox] 11:03:10 INFO - mkdir: /builds/slave/test/build/application 11:03:10 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:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:03:28 INFO - Reading from file tmpfile_stdout 11:03:28 INFO - Output received: 11:03:28 INFO - /builds/slave/test/build/application/firefox/firefox 11:03:28 INFO - Running post-action listener: _resource_record_post_action 11:03:28 INFO - [mozharness: 2017-08-16 18:03:28.164705Z] Finished install step (success) 11:03:28 INFO - [mozharness: 2017-08-16 18:03:28.164988Z] Running setup-mitmproxy step. 11:03:28 INFO - Running pre-action listener: _resource_record_pre_action 11:03:28 INFO - Running main action method: setup_mitmproxy 11:03:28 INFO - Skipping: mitmproxy is not required 11:03:28 INFO - Running post-action listener: _resource_record_post_action 11:03:28 INFO - [mozharness: 2017-08-16 18:03:28.166211Z] Finished setup-mitmproxy step (success) 11:03:28 INFO - [mozharness: 2017-08-16 18:03:28.166434Z] Running run-tests step. 11:03:28 INFO - Running pre-action listener: _resource_record_pre_action 11:03:28 INFO - Running pre-action listener: _set_gcov_prefix 11:03:28 INFO - Running main action method: run_tests 11:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:03:28 INFO - Python 2.7.3 11:03:28 INFO - Return code: 0 11:03:28 INFO - grabbing minidump binary from tooltool 11:03:28 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:03:28 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 0x23560b0>, '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 0x2356fb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2357440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:03:28 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:03:28 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:03:28 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:03:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:03:28 INFO - Return code: 0 11:03:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:03:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:03:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:03:28 INFO - ENV: RUST_BACKTRACE is now full 11:03:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:03:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-027 --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:03:28 INFO - Using env: {'DISPLAY': ':0', 11:03:28 INFO - 'HOME': '/home/cltbld', 11:03:28 INFO - 'LANG': 'en_US.UTF-8', 11:03:28 INFO - 'LANGUAGE': 'en_US:en', 11:03:28 INFO - 'LOGNAME': 'cltbld', 11:03:28 INFO - 'MAIL': '/var/mail/cltbld', 11:03:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:03:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:28 INFO - 'MOZ_NO_REMOTE': '1', 11:03:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:03:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:03:28 INFO - 'NO_EM_RESTART': '1', 11:03:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:28 INFO - 'PWD': '/builds/slave/test', 11:03:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:03:28 INFO - 'RUST_BACKTRACE': 'full', 11:03:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:03:28 INFO - 'SHELL': '/bin/bash', 11:03:28 INFO - 'SHLVL': '1', 11:03:28 INFO - 'STYLO_FORCE_ENABLED': '1', 11:03:28 INFO - 'TERM': 'linux', 11:03:28 INFO - 'TMOUT': '86400', 11:03:28 INFO - 'USER': 'cltbld', 11:03:28 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174', 11:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:28 INFO - '_': '/tools/buildbot/bin/python'} 11:03:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YbqntiLNQ_uKrat-Wa379w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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:03:28 INFO - mozversion application_buildid: 20170816171050 11:03:28 INFO - mozversion application_changeset: 07ce8c96222d533fc89c02802143d35d7c351f9c 11:03:28 INFO - mozversion application_display_name: Nightly 11:03:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:03:28 INFO - mozversion application_name: Firefox 11:03:28 INFO - mozversion application_remotingname: firefox 11:03:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:03:28 INFO - mozversion application_vendor: Mozilla 11:03:28 INFO - mozversion application_version: 57.0a1 11:03:28 INFO - mozversion platform_buildid: 20170816171050 11:03:28 INFO - mozversion platform_changeset: 07ce8c96222d533fc89c02802143d35d7c351f9c 11:03:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:03:28 INFO - mozversion platform_version: 57.0a1 11:03:28 INFO - using testdate: 1502906608 11:03:28 INFO - actual date: 1502906608 11:03:28 INFO - starting webserver on 'localhost:57466' 11:03:28 INFO - SUITE-START | Running 2 tests 11:03:28 INFO - TEST-START | dromaeo_css 11:03:28 INFO - Initialising browser for dromaeo_css test... 11:03:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57466/getInfo.html -profile /tmp/tmpHMMTNt/profile 11:03:28 INFO - TEST-INFO | started process 22085 (/builds/slave/test/build/application/firefox/firefox http://localhost:57466/getInfo.html) 11:03:34 INFO - TEST-INFO | 22085: exit 0 11:03:34 INFO - Browser initialized. 11:03:34 INFO - Running cycle 1/1 for dromaeo_css test... 11:03:34 INFO - TEST-INFO | started process 22318 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHMMTNt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:03:35 INFO - PID 22318 | 11:03:35 INFO - PID 22318 | (/builds/slave/test/build/application/firefox/firefox:22372): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:03:35 INFO - PID 22318 | 11:03:45 INFO - PID 22318 | RSS: Main: 167714816 11:03:45 INFO - PID 22318 | 11:06:44 INFO - PID 22318 | Cycle 1(1): loaded http://localhost:57466/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57466/tests/dromaeo/cssquery-ext.html) 11:06:45 INFO - PID 22318 | RSS: Main: 175087616 11:06:45 INFO - PID 22318 | 11:09:47 INFO - PID 22318 | Cycle 1(1): loaded http://localhost:57466/tests/dromaeo/cssquery-ext.html (next: http://localhost:57466/tests/dromaeo/cssquery-jquery.html) 11:09:48 INFO - PID 22318 | RSS: Main: 172666880 11:09:48 INFO - PID 22318 | 11:12:50 INFO - PID 22318 | Cycle 1(1): loaded http://localhost:57466/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57466/tests/dromaeo/cssquery-mootools.html) 11:12:50 INFO - PID 22318 | RSS: Main: 174555136 11:12:50 INFO - PID 22318 | 11:15:52 INFO - PID 22318 | Cycle 1(1): loaded http://localhost:57466/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57466/tests/dromaeo/cssquery-prototype.html) 11:15:53 INFO - PID 22318 | RSS: Main: 173154304 11:15:53 INFO - PID 22318 | 11:18:56 INFO - PID 22318 | Cycle 1(1): loaded http://localhost:57466/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57466/tests/dromaeo/cssquery-yui.html) 11:18:57 INFO - PID 22318 | RSS: Main: 175927296 11:18:57 INFO - PID 22318 | 11:21:59 INFO - PID 22318 | Cycle 1(1): loaded http://localhost:57466/tests/dromaeo/cssquery-yui.html (next: http://localhost:57466/tests/dromaeo/cssquery-dojo.html) 11:21:59 INFO - PID 22318 | RSS: Main: 176713728 11:21:59 INFO - PID 22318 | 11:21:59 INFO - PID 22318 | __start_tp_report 11:21:59 INFO - PID 22318 | _x_x_mozilla_page_load 11:21:59 INFO - PID 22318 | _x_x_mozilla_page_load_details 11:21:59 INFO - PID 22318 | |i|pagename|runs| 11:21:59 INFO - PID 22318 | |0;dojo.html;3124.3595063012085;3135.9216019599494;3150.117966969249;3163.367326534693;3168.334649723558;6069.544784141185;6172.567920245585;6201.224846894138;6219.036049412342;6234.283057448395;6043.788467403641;6138.416850399216;6140.201773769404;6149.723262453189;6150.323464418911;5569.805056823012;5577.944220557803;5582.441755824426;5626;5763.827085187451;5587.972060139694;5669.036263835158;5709.71451427429;5736.684482353469;5745.770169193227;5422.078246698071;5681.630694004876;5682.119271512922;5739.053056245735;5782.045962416196;5582.525485333752;5597.188407680864;5640.85897852557;5649.406812284692;5677.772889084432;5544.473275038865;5633.32400111985;5694.373618901917;5704.942950570483;5710.657360558338;5899.911501327483;6008.429199226067;6042.607230530001;6072.817815465543;6087.026075827891;6600.669966501656;6658.167729033871;6661.467082633426;6665.366790154928;6673.065770792093;6709.3290670933;6734.326567343275;6758.628275444848;6764.255931847493;6773.796786096425;6180.134781130645;6196.10156527301;6199.87600247997;6281.214848144028;6283.654399008053;6661.200655921223;6760.560563563353;6761;6909.965450172717;6917.654117294216;6444.323346048691;6462.870742585216;6471.644059576768;6577.80266592003;6614.206295244601;6592.835179120464;6594.472442204708;6674.365935236145;6684.799456016327;6685.0640910272605;6539.411452969255;6561.442277406379;6637.668116594151;6651.600903945779;6660.56706314088;6389.009703495965;6540.149780528501;6575.769848055295;6651.800445986628;6669.099671544376;5474.972625136849;5479.4520547944885;5480.588955828249;5482.396936337;5503.889922201533;4613.169629466728;4620.330052142421;4648.535146485325;4668.603168730696;4685.531446855287;5814.796482123105;5816.127580862904;5831.79588714393;5894.115882617642;5901.321348044944;6659.134312539339;6666.333366663392;6672.132622759011;6682.231543372477;6682.732690692318;5771.480566749013;5829.88340233193;5838.12428135783;5841.50347220491;5845.1232315153065;5778.624389414675;5850.12248162779;5909.172715819789;5930.555208359304;5939.732712027969;6423.646699431576;6472.126262954441;6531.5427920046095;6579.30917253691;6582.868342633216;6687.531872769054;6728.529002969745;6729.394354508117;6741.865162696817;6749.055132281583;6448.451881590117;6451.1936007999;6459.547831651739;6481.125048118443;6551.4103730664465;6365.427111559982;6487.3188315226225;6495.9025614614875;6603.933960660332;6604.933950660432;6580.605163690194;6672.866542669217;6752.3922846944015;6752.966235168793;6753.966230168818;6502.934970650233;6535.313792051957;6538.215414150332;6539.444147247537;6567.671616419256;6292.087647291209;6375.968120159369;6409.8397540060005;6451.806445806634;6531.281559028598;6424.743010279536;6450.41946224842;6461.57999730016;6475.449586785176;6478.093066970534;6516.902246466212;6528.804135875931;6565.671716414256;6567.047778072249;6627.304133066152;6255.124282600531;6279.246490421178;6324.114623952558;6360.236771587439;6370.458511026614 11:21:59 INFO - PID 22318 | |1;ext.html;821.544043056104;849.7238397520805;874.5059041641472;881.1584936385748;1178.3519064514526;31863.203419914484;32527.67472325276;43437.13125737487;43586.782066089705;43875.78062109685;43474.56525434752;43836.3424548632;43881.780591097;43975.78012109943;43996.56003439965;7275.745348912789;7675.769726908187;7766.922330776676;7822.9608851955945;7868.763937081897;7627.161012288644;7690.807729806744;7691.461597688164;7710.113336966237;7721.150673425918;7699.961500192491;7742.690292388298;7742.883856742125;7752.689892404297;7759.844803103934;7693.038370203724;7693.653785579662;7702.345300649424;7721.7683469496005;7728.459007869451;7602.695892164307;7633.160352361291;7637.083549974039;7661;7675.232476752336;40542.79728601338;40634.59365406338;40789.796051019555;40812.38781418281;40987.59012409898;3579.2662504186837;3617.6201498842506;3621.800800955946;3624.619414961444;3629.6370362963758;32641.67358326434;32678.346433071205;32712.509312360333;32776.50835237473;32824.34351312984;5099.46455622158;5104.923426148611;5124.231365295198;5125.487451254845;5148.613853960968;193730;194510.99999999857;194522;195503;196208;50918.745406272734;51095.23357149646;51107.233391499154;51125;51130.721731957;52994.20508692373;53011.469885300656;53056;53069.73465132727;53130;16738.58153546171;17183.226754796066;17217.827821721872;17225.56936076608;17255.91372043157;12075.637730868088;12275.93862030684;13981.580552583438;14188.361523731457;14267.500637477639;14571;14571.92714036423;14625.26873656313;14752.704945901021;14780.187089710169;5830.737616807254;5965.522758179335;5982.341942386334;6014.8195554133445;6019.969900150471;1005.7535903703639;1012.5848402155228;1013.9188864890939;1018.2719355660738;1030.9175265978704;23118.844057797043;23214.76785232126;23387.41531461693;23446.765532344798;23460.296191113954;1125.071815752008;1130.649498655403;1134.478140055567;1137.3119262846024;1141.275290190735;12339.07456940715;12542.811857821958;12575.5598554052;12585.307808070462;12594.685132871751;237388;240817;240817;241466;241600;54578.72710636501;55480.99999999919;55503.16745248825;55548.72225638846;55581.7220913901;1457.6428774949993;1507.5929499035199;1521.5967768541345;1522.4519173097676;1525.3983317516563;22912.4271893204;22925.541489170457;22976.885115574314;22984.080636774343;23074.423139421648;38300.6169938297;38465;38564;38585;38621.6137838618;195238;196404;196618;196814;197109;34760.82619586886;34980.82509587436;36975.44536832003;37698.43452348272;37700.62299376971;46112;47825;48585;48699.026019478704;48791.268130977354;34941.301173976884;35010;35303.64696353004;35613.821930891216;35655.465168023016;35353.646463535035;35358.823205883804;35373.82313088418;35916.82041589775;36073.458898117075;39035.41446878242;39761;40013.1997360057;40115.39826902657;40203;33948.151296217795;34110.317793644484;34160.658393415746;34234.48648270328;34582.48126278057;24010.51978960446;24456.510869782862;24856.254312370656;24869.129580464596;25007.874645640626 11:21:59 INFO - PID 22318 | |2;jquery.html;563.9125935479999;573.5812856614674;575.2435547255358;579.3164066401649;583.9474447299743;22268.66597001046;22632.321030369094;23465.06139754408;23467.882660586714;23550.88224558877;26600.73399266007;26964.325891852714;27110;27170;27185.320366990836;6430.5177111716575;6561.179852518435;6561.606303621798;6573.868522629545;6600.306967768363;6024.2168518092585;6468.514861385392;6557.409833115019;6569.638669873155;6579.276279609239;6527.249366322862;6528.183977002875;6540.411362977331;6549.639769812658;6551.967240163816;6495.480361571086;6500.674966251692;6515.4787616990525;6525.771597994071;6534.444572211368;6053.606515576517;6070.089486577004;6070.726817293188;6077.969610151965;6088.238970128734;28919.710802891914;29999.550006749916;30624.846875765703;30790.23024424385;30919.690803091908;1884.3593178319438;1924.7594050743658;1927.469945764912;1930.4980705016796;1931.0730849192466;19803.801961980338;19984.300549480842;21179;21234.362969110796;21237.256696015564;2386.5942789725614;2422.515496900627;2423.575874222004;2435.8294919355653;2535.239428171541;137898;148583;155223;155317;155415.2229238858;14728.232612086618;15169.967779803857;15370.615734606501;15745.44890928812;15996.280167392495;23850;23867.641985370232;23925.401864953514;23970;23973.400664983514;9645.37305075168;9687.612495500241;9893.65372211969;9976;10663.94704662143;8843.469391836381;8858.557072146366;8893.644254229888;8928.508932008803;8937.106289371011;9229.53852307382;9273.397229180082;9457.574409151604;9463.432194068379;9848.901510984942;2733.221032005869;2734.4941185880675;2834.6881842997254;2841.843698596556;2872.5547540131315;957.3777044920718;959.9057074934581;962.5283611030544;976.2921881635757;978.6476868327396;19664.21343146258;23757.643635345485;24253.757462425503;25385;25581.616275755878;1054.7890421915495;1072.5495291977388;1072.7908057928785;1075.091547642241;1078.7411021354976;11445.771084578428;11611.187216894905;11640;11649.650510484698;11719.238249513754;530360;534140;545030;546205;553124;121292;123020;125144.37427812803;125731.3713431427;126146;1137.2039572299423;1187.8040123379546;1250.2186133666457;1297.941592628334;1302.0299876592;21077.6838347428;21333;21782.891085545103;21889.12443502306;22474.101035959033;57609.71195144165;58010;59003.70498147482;59408.70295648494;60621;64959.35040649533;65114.34885651083;67734;67948.6602566984;68370.65814670894;15018.924905375403;15141.015833971198;15301.923490382476;15415.306311216247;15443.536693899201;24009.759902400754;24012.87993560021;24214;24456.6331505024;24544.140955066487;18545.165467554263;18559.35042273514;18760;18772.155253013374;18830.717539237197;16198.919005404898;17093.658126837643;17165.485035448957;17849;18349.908250458662;18972.146253418352;20163.59672806565;20215.29246476366;20430.795692042888;20496.69254961147;14837.851621483647;15311.693766124392;15320.157391343582;15365.6158596036;15385.230738462807;18679.252829886653;18880.905595471933;19168.52078697988;19205.231790728772;19214.8078519213 11:21:59 INFO - PID 22318 | |3;mootools.html;1381.2058066611698;1446.1395469695528;1448.493027440396;1467.9412823487064;1469.4195792661892;14075.225862577554;14732.18972956487;14832;14843.480478183266;14846;13705.314734263271;14071.92964035181;14827.851721482783;14892.329845156966;14919.701605967874;2591.3910231095724;2626.0283695032767;2633.986830065857;2636.9208923732317;2642.352623607219;2559.9744002560014;2585.28904551249;2623.22614828626;2682.919512414631;2688.2741659751855;2691.434798692277;2745.1215611004486;2746.6978632350424;2751.610436729449;2752.628395166657;2622.357522407003;2666.173486219259;2675.705672376037;2704.3509557706207;2706.228724813419;2508.736582658813;2533.505966336565;2556.335352808265;2598.1296265751;2599.6620439342955;14212.786808197885;14214.862810975097;14251.857481425157;14263.714725705531;14269.857301426957;7159.427245820322;7388.815279617998;7403.111626604788;7425.071866016748;7430.702771889118;4747.715137091785;4815.927761083586;4973.5523802857915;5028.874278143076;5036.06832735945;1869.0094250047496;1880.1445342369188;1881.9529511762178;1881.9811801881942;1906.6663333916508;23816.880915595484;24170.879145604336;24218.152364667152;24426.877865610735;24459;6411.461249300134;6500.609963402211;6620.437262832616;6732.697028633724;6773.864522709617;19021.14404851785;19039.714404283946;19039.904800476186;19051.714224286647;19105.90447047756;7372.410207183412;7659.885101723478;7662.885056724153;7686;7700.114486834086;6841.144856892888;6914.8617027660175;6916.100906882073;6970.546914450544;7062.364387205176;5287.709175995357;5450.291462109901;5508.476694713996;5516.806911758069;5676.148577713376;1483.9629009274854;1533.2333833083458;1658.6433916707965;1693.5596744846428;1727.395411605953;864.1444969480295;875.9474431534128;890.3633901760272;893.5130353957109;897.7845317123844;7797.571133587594;8213.507189568645;8306.293965013041;8360.498370097814;8365.665373385118;923.2383283790899;940.5250348574064;958.3435346787472;986.7730422002979;999.8050380175941;5517.97917385285;5538;5646.830595082154;5699.658020518782;5739.483446489836;19437.4168774937;19447.708284375745;20254.189832406537;20256.59486810285;20261.696074558895;19542.902285488766;19721.802781972285;19806.20775168977;19832.801671983383;19868.60262794736;10557.366558006544;11099.223054386115;11109.944450277697;11200.655921289497;11270.154738394653;7960.124386317616;8088.393370497119;8253.917460825554;8260.876086858581;8388.286995605198;16234.431794887123;16237.431689890796;16300.836991629933;16347.836521635107;16791.244394002544;16012;16372.918135409247;16380.918095409446;16662.50012499627;16710.66578668444;13141.73716525645;13482.460701571827;13498.25759583193;13499.122557033761;13508.121972072177;13373.197608143542;13528.93235533816;13562.186268823902;13668.111572747739;13698.52055178064;9838.505098524976;10531.315464494783;11227.831582526433;11262.436878155895;11534.250273732181;10940.56237750513;10999.23005389615;11158.442077896236;11271.49278282463;11309.095272378354;9412.57643406062;9533.47565883883;9538.093881081286;9541;9542.42745435276;7339.926600733925;7343.70625174987;7372.299631534996;7372.815679608053;7388.556686598821;7156.821079473055;7301.160366557914;7306.086739157606;7352.852942941005;7382.889256661262 11:21:59 INFO - PID 22318 | |4;prototype.html;495.49459551257706;499.4431209201741;504.45266885429305;507.2543361258948;507.69269131985124;6193.81418557444;6196.59722118062;6235.1894253747005;6289.654069026202;6345;5995.550333724967;6057.182280392146;6097.756089756415;6147.231596050494;6173.938260617392;4817.927731084036;4988.825391111293;5035.974820125894;5061.822836200734;5086.872828179288;4871.926921096187;4894.143524883149;4903.926441103386;4921.80312787488;4964.602831773467;4855.611551075923;4865.927011094836;4872.902541949159;4892.119418504685;4900;4875.829345972892;4891.535304146136;4913.508649135094;4938.234573641073;4964.304997300381;4384.4519435070615;4403.515613282536;4406.405135306724;4417.337399390117;4430.29115341547;5933.614315069507;6088.756449742049;6096.420840020191;6128.785492507785;6148.78479253224;5575.944240557624;5589.804356847531;5625.887482250332;5638.830835074913;5684.54523638108;6547.9345206547805;6549.279579246279;6570.671466426707;6581.617860249354;6585.736570537173;4761.59526440253;4772.76136193189;4796.424429068534;4808.807647694088;4844.418669759616;6554.70503827329;6678.6326752028135;6812;6860.759873404407;7012.894806577905;5436.374816896028;5467.125259958467;5498.86564658141;5505.174223866372;5518.420565840609;5586.385497595261;5598.944010559923;5606.383882395832;5634.915476267859;5658.405867383865;5980.790672326535;6006.459418652343;6018.6990650467305;6033.939660603425;6034.48706859913;6652.800415987528;6703.329667033355;6749.223839258548;6762.729490820312;6764.458843292525;1943.5626983928785;1945.747052883116;1948.4836518322657;1953.3846838245906;1980.4949737816764;1367.282176857158;1409.3023953143054;1430.6051599690218;1431.7709166533514;1459.6277949122905;5703.315602127771;5711.885762284731;5752.9712351437975;5767.163761254595;5868.149118377812;6619.801405957829;6639.668016599151;6720.22717387497;6791.049253104569;6829.224401655506;5747.971260143672;5803.9419605803405;5825.650460972355;5846.795362162304;5856;3582.2477279771538;3589.300086483163;3642.3625865473437;3659.9634003660153;3675.46705727668;6517.576357536745;6530.640814755234;6562.868742625121;6586.934130658728;6631.3700198481065;6823.283555226729;6876.177812879591;6901.585904845725;6913.861722765517;6953.200381956039;6621.702023408958;6623.569467984566;6655.567388119756;6659.9001014984815;6661.235587231342;6807.659617019228;6818.249992500921;6853.828654283683;6862.931370686429;6898.757903681223;6693.129893114059;6712.731490740316;6726.630035348103;6740.4496965699145;6742.6291553965;6658.966705166443;6663.733450662113;6674.466042716474;6676.332366763285;6681.832954176184;5490.505854472957;5514.889702206014;5531.8893622128135;5533.446655334435;5554.833354999357;6503.512236582181;6627.1716035495565;6734.225564060196;6866.283429142714;6915.723371065101;6555.901661474986;6562.442192413699;6570.178727659043;6573.638449885302;6609.537332386879;6595.241547222131;6598.769043083661;6613.173353330833;6633.800985970429;6660.433863121689;6290.308066112632;6402.431404305975;6433.678316084271;6513.478921686159;6544.3311955452955;6466.547341686037;6565.096122124531;6582.341765823379;6607.636579988147;6613.735450581923;6291.182146320949;6364.268109167319;6381.8723625528155;6425.293217745951;6469.320721324193 11:21:59 INFO - PID 22318 | |5;yui.html;874.1346067393279;889.719738282441;900.3337530227625;900.9549522523872;910.4946754551225;18631.5342116447;19913.3030343938;19924.302649407273;19979;20063.398098057045;16234.350625974976;16308.510744677678;17650.646987060252;22146.88926555365;22167.44581385466;1047.83234682451;1073.1200415659162;1073.538378497246;1073.8550295710097;1073.8979796919318;1007.9899201007969;1053.2364036073823;1053.441675911768;1055.6305293147414;1068.4283908109164;1123.2642619084472;1125.431657013207;1125.8367536707171;1125.8930401611833;1126.2003977176214;1125.0999200639455;1126.0147371050332;1127.3010733345297;1128.0129886349403;1130.513879032016;819.7581713394563;820.9343252539783;821.2116368286452;823.5305875650881;825.2449009156614;19917;20105.39683809488;20140.19439222444;20320.28878989228;20892.686609700868;21554.245601404033;21634.675479867816;21981.67027494589;22303.442413939618;22455.887720561455;16761.83238167627;16779.664406711796;17159.485215443558;17165.828341716548;17240.396586119427;3631.092226943264;3642.908927276813;3642.908927276813;3649.069487280753;3654.26914617075;130446;130856.34571827175;131423.34288328496;131774;135209.32395337961;16933.661326773643;17128;17183.57041073983;17396;17408.303667853397;30313;31321;31876.521852172235;32104.83947580247;32159.678403216138;8750.781230469162;8900.154485323885;9003.459792412476;9034.954825225963;9142.131497507726;3937.527496700357;4090.550039495653;4092.6112019358116;4093.91812163761;4103.835846566164;5183.97408012965;5264.289320941701;5264.473552644781;5293.153095504777;5321.228421878806;970.7476056225291;975.7365511311906;992.1071036067452;997.8253805584139;998.4408731110601;682.74738346812;685.3626127701285;691.2672567078912;703.7501686901179;705.4955706669756;11804.704882377837;11932;11937.88062119385;12415.379231038412;12505.187162834387;680.452235950065;698.179638924262;708.348319546019;709.1738125084322;720.5280541245493;5432.456754324536;5529.695866727368;5548.611597188239;5549.334079910356;5650.039493286151;110711;111303;112739;113265.4336728311;114219;97037;97981;98804;98898.53855115037;99849;86729;89788;89881;92134.07865921255;92536.53731731433;12337.50649973991;12777.297248651414;12806.295653739135;13013.414396351998;13019.414126364525;39364;39531.20937581175;40438.59561404466;41955;42137.36793948032;71701.28298716946;72381.6380918092;73520.26479735348;74279.62860185665;78969.60515197387;18647.160877760805;19293.324733634254;19574.11916463734;19972.300969466;20171.193152273743;22266.443338916655;22313.107475700792;22638.320850374515;22642.43393915165;23004.07983680634;19025.71461428052;19220.80779192246;19620.90189549043;20050.298239561544;20246.392608221777;19221.807781922;19311.324103656305;19418.320358787376;19421.708674370177;19512.414627561197;17776.91111544434;18171.818281817013;18367;18386;18438.35465758692;13921.512747053805;13961.650958725706;14048.859511404755;14110.365033573718;14144.646383840487;14703.852961470247;15008.924955375152;15130;15343.846561534241;15648.217589120726 11:21:59 INFO - PID 22318 | __end_tp_report 11:21:59 INFO - PID 22318 | __start_cc_report 11:21:59 INFO - PID 22318 | _x_x_mozilla_cycle_collect,369 11:21:59 INFO - PID 22318 | __end_cc_report 11:21:59 INFO - PID 22318 | __startTimestamp1502907719365__endTimestamp 11:21:59 INFO - PID 22318 | 11:21:59 INFO - PID 22318 | ------- Summary: start ------- 11:21:59 INFO - PID 22318 | Number of tests: 6 11:21:59 INFO - PID 22318 | 11:21:59 INFO - PID 22318 | [#0] dojo.html Cycles:165 Average:6123.90 Median:6375.97 stddev:718.23 (11.3%) stddev-sans-first:680.80 11:21:59 INFO - PID 22318 | Values: 3124.4 3135.9 3150.1 3163.4 3168.3 6069.5 6172.6 6201.2 6219.0 6234.3 6043.8 6138.4 6140.2 6149.7 6150.3 5569.8 5577.9 5582.4 5626.0 5763.8 5588.0 5669.0 5709.7 5736.7 5745.8 5422.1 5681.6 5682.1 5739.1 5782.0 5582.5 5597.2 5640.9 5649.4 5677.8 5544.5 5633.3 5694.4 5704.9 5710.7 5899.9 6008.4 6042.6 6072.8 6087.0 6600.7 6658.2 6661.5 6665.4 6673.1 6709.3 6734.3 6758.6 6764.3 6773.8 6180.1 6196.1 6199.9 6281.2 6283.7 6661.2 6760.6 6761.0 6910.0 6917.7 6444.3 6462.9 6471.6 6577.8 6614.2 6592.8 6594.5 6674.4 6684.8 6685.1 6539.4 6561.4 6637.7 6651.6 6660.6 6389.0 6540.1 6575.8 6651.8 6669.1 5475.0 5479.5 5480.6 5482.4 5503.9 4613.2 4620.3 4648.5 4668.6 4685.5 5814.8 5816.1 5831.8 5894.1 5901.3 6659.1 6666.3 6672.1 6682.2 6682.7 5771.5 5829.9 5838.1 5841.5 5845.1 5778.6 5850.1 5909.2 5930.6 5939.7 6423.6 6472.1 6531.5 6579.3 6582.9 6687.5 6728.5 6729.4 6741.9 6749.1 6448.5 6451.2 6459.5 6481.1 6551.4 6365.4 6487.3 6495.9 6603.9 6604.9 6580.6 6672.9 6752.4 6753.0 6754.0 6502.9 6535.3 6538.2 6539.4 6567.7 6292.1 6376.0 6409.8 6451.8 6531.3 6424.7 6450.4 6461.6 6475.4 6478.1 6516.9 6528.8 6565.7 6567.0 6627.3 6255.1 6279.2 6324.1 6360.2 6370.5 11:21:59 INFO - PID 22318 | 11:21:59 INFO - PID 22318 | [#1] ext.html Cycles:180 Average:38939.39 Median:19845.54 stddev:54867.39 (276.5%) stddev-sans-first:54946.66 11:21:59 INFO - PID 22318 | Values: 821.5 849.7 874.5 881.2 1178.4 31863.2 32527.7 43437.1 43586.8 43875.8 43474.6 43836.3 43881.8 43975.8 43996.6 7275.7 7675.8 7766.9 7823.0 7868.8 7627.2 7690.8 7691.5 7710.1 7721.2 7700.0 7742.7 7742.9 7752.7 7759.8 7693.0 7693.7 7702.3 7721.8 7728.5 7602.7 7633.2 7637.1 7661.0 7675.2 40542.8 40634.6 40789.8 40812.4 40987.6 3579.3 3617.6 3621.8 3624.6 3629.6 32641.7 32678.3 32712.5 32776.5 32824.3 5099.5 5104.9 5124.2 5125.5 5148.6 193730.0 194511.0 194522.0 195503.0 196208.0 50918.7 51095.2 51107.2 51125.0 51130.7 52994.2 53011.5 53056.0 53069.7 53130.0 16738.6 17183.2 17217.8 17225.6 17255.9 12075.6 12275.9 13981.6 14188.4 14267.5 14571.0 14571.9 14625.3 14752.7 14780.2 5830.7 5965.5 5982.3 6014.8 6020.0 1005.8 1012.6 1013.9 1018.3 1030.9 23118.8 23214.8 23387.4 23446.8 23460.3 1125.1 1130.6 1134.5 1137.3 1141.3 12339.1 12542.8 12575.6 12585.3 12594.7 237388.0 240817.0 240817.0 241466.0 241600.0 54578.7 55481.0 55503.2 55548.7 55581.7 1457.6 1507.6 1521.6 1522.5 1525.4 22912.4 22925.5 22976.9 22984.1 23074.4 38300.6 38465.0 38564.0 38585.0 38621.6 195238.0 196404.0 196618.0 196814.0 197109.0 34760.8 34980.8 36975.4 37698.4 37700.6 46112.0 47825.0 48585.0 48699.0 48791.3 34941.3 35010.0 35303.6 35613.8 35655.5 35353.6 35358.8 35373.8 35916.8 36073.5 39035.4 39761.0 40013.2 40115.4 40203.0 33948.2 34110.3 34160.7 34234.5 34582.5 24010.5 24456.5 24856.3 24869.1 25007.9 11:21:59 INFO - PID 22318 | 11:21:59 INFO - PID 22318 | [#2] jquery.html Cycles:180 Average:37249.58 Median:24113.44 stddev:91124.21 (377.9%) stddev-sans-first:91338.20 11:21:59 INFO - PID 22318 | Values: 563.9 573.6 575.2 579.3 583.9 22268.7 22632.3 23465.1 23467.9 23550.9 26600.7 26964.3 27110.0 27170.0 27185.3 6430.5 6561.2 6561.6 6573.9 6600.3 6024.2 6468.5 6557.4 6569.6 6579.3 6527.2 6528.2 6540.4 6549.6 6552.0 6495.5 6500.7 6515.5 6525.8 6534.4 6053.6 6070.1 6070.7 6078.0 6088.2 28919.7 29999.6 30624.8 30790.2 30919.7 1884.4 1924.8 1927.5 1930.5 1931.1 19803.8 19984.3 21179.0 21234.4 21237.3 2386.6 2422.5 2423.6 2435.8 2535.2 137898.0 148583.0 155223.0 155317.0 155415.2 14728.2 15170.0 15370.6 15745.4 15996.3 23850.0 23867.6 23925.4 23970.0 23973.4 9645.4 9687.6 9893.7 9976.0 10663.9 8843.5 8858.6 8893.6 8928.5 8937.1 9229.5 9273.4 9457.6 9463.4 9848.9 2733.2 2734.5 2834.7 2841.8 2872.6 957.4 959.9 962.5 976.3 978.6 19664.2 23757.6 24253.8 25385.0 25581.6 1054.8 1072.5 1072.8 1075.1 1078.7 11445.8 11611.2 11640.0 11649.7 11719.2 530360.0 534140.0 545030.0 546205.0 553124.0 121292.0 123020.0 125144.4 125731.4 126146.0 1137.2 1187.8 1250.2 1297.9 1302.0 21077.7 21333.0 21782.9 21889.1 22474.1 57609.7 58010.0 59003.7 59408.7 60621.0 64959.4 65114.3 67734.0 67948.7 68370.7 15018.9 15141.0 15301.9 15415.3 15443.5 24009.8 24012.9 24214.0 24456.6 24544.1 18545.2 18559.4 18760.0 18772.2 18830.7 16198.9 17093.7 17165.5 17849.0 18349.9 18972.1 20163.6 20215.3 20430.8 20496.7 14837.9 15311.7 15320.2 15365.6 15385.2 18679.3 18880.9 19168.5 19205.2 19214.8 11:21:59 INFO - PID 22318 | 11:21:59 INFO - PID 22318 | [#3] mootools.html Cycles:180 Average:9010.26 Median:2598.90 stddev:6203.82 (238.7%) stddev-sans-first:6194.74 11:21:59 INFO - PID 22318 | Values: 1381.2 1446.1 1448.5 1467.9 1469.4 14075.2 14732.2 14832.0 14843.5 14846.0 13705.3 14071.9 14827.9 14892.3 14919.7 2591.4 2626.0 2634.0 2636.9 2642.4 2560.0 2585.3 2623.2 2682.9 2688.3 2691.4 2745.1 2746.7 2751.6 2752.6 2622.4 2666.2 2675.7 2704.4 2706.2 2508.7 2533.5 2556.3 2598.1 2599.7 14212.8 14214.9 14251.9 14263.7 14269.9 7159.4 7388.8 7403.1 7425.1 7430.7 4747.7 4815.9 4973.6 5028.9 5036.1 1869.0 1880.1 1882.0 1882.0 1906.7 23816.9 24170.9 24218.2 24426.9 24459.0 6411.5 6500.6 6620.4 6732.7 6773.9 19021.1 19039.7 19039.9 19051.7 19105.9 7372.4 7659.9 7662.9 7686.0 7700.1 6841.1 6914.9 6916.1 6970.5 7062.4 5287.7 5450.3 5508.5 5516.8 5676.1 1484.0 1533.2 1658.6 1693.6 1727.4 864.1 875.9 890.4 893.5 897.8 7797.6 8213.5 8306.3 8360.5 8365.7 923.2 940.5 958.3 986.8 999.8 5518.0 5538.0 5646.8 5699.7 5739.5 19437.4 19447.7 20254.2 20256.6 20261.7 19542.9 19721.8 19806.2 19832.8 19868.6 10557.4 11099.2 11109.9 11200.7 11270.2 7960.1 8088.4 8253.9 8260.9 8388.3 16234.4 16237.4 16300.8 16347.8 16791.2 16012.0 16372.9 16380.9 16662.5 16710.7 13141.7 13482.5 13498.3 13499.1 13508.1 13373.2 13528.9 13562.2 13668.1 13698.5 9838.5 10531.3 11227.8 11262.4 11534.3 10940.6 10999.2 11158.4 11271.5 11309.1 9412.6 9533.5 9538.1 9541.0 9542.4 7339.9 7343.7 7372.3 7372.8 7388.6 7156.8 7301.2 7306.1 7352.9 7382.9 11:21:59 INFO - PID 22318 | 11:21:59 INFO - PID 22318 | [#4] prototype.html Cycles:180 Average:5587.26 Median:6113.27 stddev:1530.27 (25.0%) stddev-sans-first:1486.07 11:21:59 INFO - PID 22318 | Values: 495.5 499.4 504.5 507.3 507.7 6193.8 6196.6 6235.2 6289.7 6345.0 5995.6 6057.2 6097.8 6147.2 6173.9 4817.9 4988.8 5036.0 5061.8 5086.9 4871.9 4894.1 4903.9 4921.8 4964.6 4855.6 4865.9 4872.9 4892.1 4900.0 4875.8 4891.5 4913.5 4938.2 4964.3 4384.5 4403.5 4406.4 4417.3 4430.3 5933.6 6088.8 6096.4 6128.8 6148.8 5575.9 5589.8 5625.9 5638.8 5684.5 6547.9 6549.3 6570.7 6581.6 6585.7 4761.6 4772.8 4796.4 4808.8 4844.4 6554.7 6678.6 6812.0 6860.8 7012.9 5436.4 5467.1 5498.9 5505.2 5518.4 5586.4 5598.9 5606.4 5634.9 5658.4 5980.8 6006.5 6018.7 6033.9 6034.5 6652.8 6703.3 6749.2 6762.7 6764.5 1943.6 1945.7 1948.5 1953.4 1980.5 1367.3 1409.3 1430.6 1431.8 1459.6 5703.3 5711.9 5753.0 5767.2 5868.1 6619.8 6639.7 6720.2 6791.0 6829.2 5748.0 5803.9 5825.7 5846.8 5856.0 3582.2 3589.3 3642.4 3660.0 3675.5 6517.6 6530.6 6562.9 6586.9 6631.4 6823.3 6876.2 6901.6 6913.9 6953.2 6621.7 6623.6 6655.6 6659.9 6661.2 6807.7 6818.2 6853.8 6862.9 6898.8 6693.1 6712.7 6726.6 6740.4 6742.6 6659.0 6663.7 6674.5 6676.3 6681.8 5490.5 5514.9 5531.9 5533.4 5554.8 6503.5 6627.2 6734.2 6866.3 6915.7 6555.9 6562.4 6570.2 6573.6 6609.5 6595.2 6598.8 6613.2 6633.8 6660.4 6290.3 6402.4 6433.7 6513.5 6544.3 6466.5 6565.1 6582.3 6607.6 6613.7 6291.2 6364.3 6381.9 6425.3 6469.3 11:21:59 INFO - PID 22318 | 11:21:59 INFO - PID 22318 | [#5] yui.html Cycles:180 Average:24556.45 Median:20283.34 stddev:33213.18 (163.7%) stddev-sans-first:33258.75 11:21:59 INFO - PID 22318 | Values: 874.1 889.7 900.3 901.0 910.5 18631.5 19913.3 19924.3 19979.0 20063.4 16234.4 16308.5 17650.6 22146.9 22167.4 1047.8 1073.1 1073.5 1073.9 1073.9 1008.0 1053.2 1053.4 1055.6 1068.4 1123.3 1125.4 1125.8 1125.9 1126.2 1125.1 1126.0 1127.3 1128.0 1130.5 819.8 820.9 821.2 823.5 825.2 19917.0 20105.4 20140.2 20320.3 20892.7 21554.2 21634.7 21981.7 22303.4 22455.9 16761.8 16779.7 17159.5 17165.8 17240.4 3631.1 3642.9 3642.9 3649.1 3654.3 130446.0 130856.3 131423.3 131774.0 135209.3 16933.7 17128.0 17183.6 17396.0 17408.3 30313.0 31321.0 31876.5 32104.8 32159.7 8750.8 8900.2 9003.5 9035.0 9142.1 3937.5 4090.6 4092.6 4093.9 4103.8 5184.0 5264.3 5264.5 5293.2 5321.2 970.7 975.7 992.1 997.8 998.4 682.7 685.4 691.3 703.8 705.5 11804.7 11932.0 11937.9 12415.4 12505.2 680.5 698.2 708.3 709.2 720.5 5432.5 5529.7 5548.6 5549.3 5650.0 110711.0 111303.0 112739.0 113265.4 114219.0 97037.0 97981.0 98804.0 98898.5 99849.0 86729.0 89788.0 89881.0 92134.1 92536.5 12337.5 12777.3 12806.3 13013.4 13019.4 39364.0 39531.2 40438.6 41955.0 42137.4 71701.3 72381.6 73520.3 74279.6 78969.6 18647.2 19293.3 19574.1 19972.3 20171.2 22266.4 22313.1 22638.3 22642.4 23004.1 19025.7 19220.8 19620.9 20050.3 20246.4 19221.8 19311.3 19418.3 19421.7 19512.4 17776.9 18171.8 18367.0 18386.0 18438.4 13921.5 13961.7 14048.9 14110.4 14144.6 14703.9 15008.9 15130.0 15343.8 15648.2 11:21:59 INFO - PID 22318 | -------- Summary: end -------- 11:21:59 INFO - PID 22318 | 11:22:00 INFO - TEST-INFO | 22318: exit 0 11:22:00 INFO - TEST-OK | dromaeo_css | took 1111496ms 11:22:00 INFO - TEST-START | kraken 11:22:00 INFO - Initialising browser for kraken test... 11:22:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57466/getInfo.html -profile /tmp/tmpmPrpD9/profile 11:22:00 INFO - TEST-INFO | started process 22697 (/builds/slave/test/build/application/firefox/firefox http://localhost:57466/getInfo.html) 11:22:05 INFO - TEST-INFO | 22697: exit 0 11:22:05 INFO - Browser initialized. 11:22:05 INFO - Running cycle 1/1 for kraken test... 11:22:05 INFO - TEST-INFO | started process 22932 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmPrpD9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:22:06 INFO - PID 22932 | 11:22:06 INFO - PID 22932 | (/builds/slave/test/build/application/firefox/firefox:22986): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:22:06 INFO - PID 22932 | 11:22:17 INFO - PID 22932 | RSS: Main: 168083456 11:22:17 INFO - PID 22932 | 11:22:19 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57466/tests/kraken/driver.html?testName=audio-beat-detection) 11:22:20 INFO - PID 22932 | RSS: Main: 174546944 11:22:20 INFO - PID 22932 | 11:22:23 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57466/tests/kraken/driver.html?testName=audio-dft) 11:22:24 INFO - PID 22932 | RSS: Main: 178601984 11:22:24 INFO - PID 22932 | 11:22:27 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57466/tests/kraken/driver.html?testName=audio-fft) 11:22:27 INFO - PID 22932 | RSS: Main: 172171264 11:22:27 INFO - PID 22932 | 11:22:30 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57466/tests/kraken/driver.html?testName=audio-oscillator) 11:22:30 INFO - PID 22932 | RSS: Main: 173158400 11:22:30 INFO - PID 22932 | 11:22:32 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57466/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:22:33 INFO - PID 22932 | RSS: Main: 173076480 11:22:33 INFO - PID 22932 | 11:22:36 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57466/tests/kraken/driver.html?testName=imaging-darkroom) 11:22:37 INFO - PID 22932 | RSS: Main: 174612480 11:22:37 INFO - PID 22932 | 11:22:40 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57466/tests/kraken/driver.html?testName=imaging-desaturate) 11:22:41 INFO - PID 22932 | RSS: Main: 174063616 11:22:41 INFO - PID 22932 | 11:22:44 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57466/tests/kraken/driver.html?testName=json-parse-financial) 11:22:44 INFO - PID 22932 | RSS: Main: 174596096 11:22:44 INFO - PID 22932 | 11:22:46 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57466/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:22:47 INFO - PID 22932 | RSS: Main: 174698496 11:22:47 INFO - PID 22932 | 11:22:49 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57466/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:22:49 INFO - PID 22932 | RSS: Main: 174428160 11:22:49 INFO - PID 22932 | 11:22:51 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57466/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:22:52 INFO - PID 22932 | RSS: Main: 174411776 11:22:52 INFO - PID 22932 | 11:22:55 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57466/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:22:55 INFO - PID 22932 | RSS: Main: 174575616 11:22:55 INFO - PID 22932 | 11:22:59 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57466/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:22:59 INFO - PID 22932 | RSS: Main: 175030272 11:22:59 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | Cycle 1(1): loaded http://localhost:57466/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57466/tests/kraken/driver.html?testName=ai-astar) 11:23:01 INFO - PID 22932 | RSS: Main: 176578560 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | __start_tp_report 11:23:01 INFO - PID 22932 | _x_x_mozilla_page_load 11:23:01 INFO - PID 22932 | _x_x_mozilla_page_load_details 11:23:01 INFO - PID 22932 | |i|pagename|runs| 11:23:01 INFO - PID 22932 | |0;ai-astar;99;107;108;110;108;108;109;101;114;109 11:23:01 INFO - PID 22932 | |1;audio-beat-detection;142;116;142;169;121;149;147;123;168;124 11:23:01 INFO - PID 22932 | |2;audio-dft;163;162;163;164;161;162;163;165;168;170 11:23:01 INFO - PID 22932 | |3;audio-fft;78;76;76;173;79;85;79;83;98;76 11:23:01 INFO - PID 22932 | |4;audio-oscillator;80;79;127;78;81;79;125;80;80;139 11:23:01 INFO - PID 22932 | |5;imaging-gaussian-blur;120;114;115;114;114;117;119;115;116;115 11:23:01 INFO - PID 22932 | |6;imaging-darkroom;150;151;152;151;152;154;151;150;150;151 11:23:01 INFO - PID 22932 | |7;imaging-desaturate;94;94;101;101;95;95;94;94;94;99 11:23:01 INFO - PID 22932 | |8;json-parse-financial;53;53;56;53;54;53;53;53;53;53 11:23:01 INFO - PID 22932 | |9;json-stringify-tinderbox;65;54;54;62;54;54;54;55;55;54 11:23:01 INFO - PID 22932 | |10;stanford-crypto-aes;76;76;77;77;75;77;77;76;78;81 11:23:01 INFO - PID 22932 | |11;stanford-crypto-ccm;124;123;131;124;131;119;122;121;125;173 11:23:01 INFO - PID 22932 | |12;stanford-crypto-pbkdf2;188;177;183;180;174;183;178;194;175;179 11:23:01 INFO - PID 22932 | |13;stanford-crypto-sha256-iterative;67;70;70;69;68;68;71;71;70;70 11:23:01 INFO - PID 22932 | __end_tp_report 11:23:01 INFO - PID 22932 | __start_cc_report 11:23:01 INFO - PID 22932 | _x_x_mozilla_cycle_collect,957 11:23:01 INFO - PID 22932 | __end_cc_report 11:23:01 INFO - PID 22932 | __startTimestamp1502907781797__endTimestamp 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | ------- Summary: start ------- 11:23:01 INFO - PID 22932 | Number of tests: 14 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#0] ai-astar Cycles:10 Average:107.30 Median:109.00 stddev:4.32 (4.0%) stddev-sans-first:3.38 11:23:01 INFO - PID 22932 | Values: 99.0 107.0 108.0 110.0 108.0 108.0 109.0 101.0 114.0 109.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:144.50 stddev:18.99 (13.1%) stddev-sans-first:20.13 11:23:01 INFO - PID 22932 | Values: 142.0 116.0 142.0 169.0 121.0 149.0 147.0 123.0 168.0 124.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#2] audio-dft Cycles:10 Average:164.10 Median:163.50 stddev:2.85 (1.7%) stddev-sans-first:2.99 11:23:01 INFO - PID 22932 | Values: 163.0 162.0 163.0 164.0 161.0 162.0 163.0 165.0 168.0 170.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#3] audio-fft Cycles:10 Average:90.30 Median:79.00 stddev:29.81 (37.7%) stddev-sans-first:31.29 11:23:01 INFO - PID 22932 | Values: 78.0 76.0 76.0 173.0 79.0 85.0 79.0 83.0 98.0 76.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.50 stddev:24.79 (31.2%) stddev-sans-first:25.71 11:23:01 INFO - PID 22932 | Values: 80.0 79.0 127.0 78.0 81.0 79.0 125.0 80.0 80.0 139.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#5] imaging-gaussian-blur Cycles:10 Average:115.90 Median:115.50 stddev:2.13 (1.8%) stddev-sans-first:1.67 11:23:01 INFO - PID 22932 | Values: 120.0 114.0 115.0 114.0 114.0 117.0 119.0 115.0 116.0 115.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#6] imaging-darkroom Cycles:10 Average:151.20 Median:151.00 stddev:1.23 (0.8%) stddev-sans-first:1.22 11:23:01 INFO - PID 22932 | Values: 150.0 151.0 152.0 151.0 152.0 154.0 151.0 150.0 150.0 151.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:94.00 stddev:3.00 (3.2%) stddev-sans-first:3.08 11:23:01 INFO - PID 22932 | Values: 94.0 94.0 101.0 101.0 95.0 95.0 94.0 94.0 94.0 99.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 11:23:01 INFO - PID 22932 | Values: 53.0 53.0 56.0 53.0 54.0 53.0 53.0 53.0 53.0 53.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:54.50 stddev:3.98 (7.3%) stddev-sans-first:2.62 11:23:01 INFO - PID 22932 | Values: 65.0 54.0 54.0 62.0 54.0 54.0 54.0 55.0 55.0 54.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#10] stanford-crypto-aes Cycles:10 Average:77.00 Median:77.00 stddev:1.63 (2.1%) stddev-sans-first:1.69 11:23:01 INFO - PID 22932 | Values: 76.0 76.0 77.0 77.0 75.0 77.0 77.0 76.0 78.0 81.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#11] stanford-crypto-ccm Cycles:10 Average:129.30 Median:124.50 stddev:15.84 (12.7%) stddev-sans-first:16.68 11:23:01 INFO - PID 22932 | Values: 124.0 123.0 131.0 124.0 131.0 119.0 122.0 121.0 125.0 173.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.10 Median:181.50 stddev:6.15 (3.4%) stddev-sans-first:6.00 11:23:01 INFO - PID 22932 | Values: 188.0 177.0 183.0 180.0 174.0 183.0 178.0 194.0 175.0 179.0 11:23:01 INFO - PID 22932 | 11:23:01 INFO - PID 22932 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:70.00 stddev:1.35 (1.9%) stddev-sans-first:1.12 11:23:01 INFO - PID 22932 | Values: 67.0 70.0 70.0 69.0 68.0 68.0 71.0 71.0 70.0 70.0 11:23:01 INFO - PID 22932 | -------- Summary: end -------- 11:23:01 INFO - PID 22932 | 11:23:02 INFO - PID 22932 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:23:02 INFO - TEST-INFO | 22932: exit 0 11:23:02 INFO - TEST-OK | kraken | took 62461ms 11:23:02 INFO - SUITE-END | took 1173s 11:23:02 INFO - Completed test suite (00:19:34) 11:23:02 INFO - javascript benchmark 11:23:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8667.00472502771, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3124.3595063012085, 3135.9216019599494, 3150.117966969249, 3163.367326534693, 3168.334649723558, 6069.544784141185, 6172.567920245585, 6201.224846894138, 6219.036049412342, 6234.283057448395, 6043.788467403641, 6138.416850399216, 6140.201773769404, 6149.723262453189, 6150.323464418911, 5569.805056823012, 5577.944220557803, 5582.441755824426, 5626.0, 5763.827085187451, 5587.972060139694, 5669.036263835158, 5709.71451427429, 5736.684482353469, 5745.770169193227, 5422.078246698071, 5681.630694004876, 5682.119271512922, 5739.053056245735, 5782.045962416196, 5582.525485333752, 5597.188407680864, 5640.85897852557, 5649.406812284692, 5677.772889084432, 5544.473275038865, 5633.32400111985, 5694.373618901917, 5704.942950570483, 5710.657360558338, 5899.911501327483, 6008.429199226067, 6042.607230530001, 6072.817815465543, 6087.026075827891, 6600.669966501656, 6658.167729033871, 6661.467082633426, 6665.366790154928, 6673.065770792093, 6709.3290670933, 6734.326567343275, 6758.628275444848, 6764.255931847493, 6773.796786096425, 6180.134781130645, 6196.10156527301, 6199.87600247997, 6281.214848144028, 6283.654399008053, 6661.200655921223, 6760.560563563353, 6761.0, 6909.965450172717, 6917.654117294216, 6444.323346048691, 6462.870742585216, 6471.644059576768, 6577.80266592003, 6614.206295244601, 6592.835179120464, 6594.472442204708, 6674.365935236145, 6684.799456016327, 6685.0640910272605, 6539.411452969255, 6561.442277406379, 6637.668116594151, 6651.600903945779, 6660.56706314088, 6389.009703495965, 6540.149780528501, 6575.769848055295, 6651.800445986628, 6669.099671544376, 5474.972625136849, 5479.4520547944885, 5480.588955828249, 5482.396936337, 5503.889922201533, 4613.169629466728, 4620.330052142421, 4648.535146485325, 4668.603168730696, 4685.531446855287, 5814.796482123105, 5816.127580862904, 5831.79588714393, 5894.115882617642, 5901.321348044944, 6659.134312539339, 6666.333366663392, 6672.132622759011, 6682.231543372477, 6682.732690692318, 5771.480566749013, 5829.88340233193, 5838.12428135783, 5841.50347220491, 5845.1232315153065, 5778.624389414675, 5850.12248162779, 5909.172715819789, 5930.555208359304, 5939.732712027969, 6423.646699431576, 6472.126262954441, 6531.5427920046095, 6579.30917253691, 6582.868342633216, 6687.531872769054, 6728.529002969745, 6729.394354508117, 6741.865162696817, 6749.055132281583, 6448.451881590117, 6451.1936007999, 6459.547831651739, 6481.125048118443, 6551.4103730664465, 6365.427111559982, 6487.3188315226225, 6495.9025614614875, 6603.933960660332, 6604.933950660432, 6580.605163690194, 6672.866542669217, 6752.3922846944015, 6752.966235168793, 6753.966230168818, 6502.934970650233, 6535.313792051957, 6538.215414150332, 6539.444147247537, 6567.671616419256, 6292.087647291209, 6375.968120159369, 6409.8397540060005, 6451.806445806634, 6531.281559028598, 6424.743010279536, 6450.41946224842, 6461.57999730016, 6475.449586785176, 6478.093066970534, 6516.902246466212, 6528.804135875931, 6565.671716414256, 6567.047778072249, 6627.304133066152, 6255.124282600531, 6279.246490421178, 6324.114623952558, 6360.236771587439, 6370.458511026614], "value": 6070.373536325831, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.544043056104, 849.7238397520805, 874.5059041641472, 881.1584936385748, 1178.3519064514526, 31863.203419914484, 32527.67472325276, 43437.13125737487, 43586.782066089705, 43875.78062109685, 43474.56525434752, 43836.3424548632, 43881.780591097, 43975.78012109943, 43996.56003439965, 7275.745348912789, 7675.769726908187, 7766.922330776676, 7822.9608851955945, 7868.763937081897, 7627.161012288644, 7690.807729806744, 7691.461597688164, 7710.113336966237, 7721.150673425918, 7699.961500192491, 7742.690292388298, 7742.883856742125, 7752.689892404297, 7759.844803103934, 7693.038370203724, 7693.653785579662, 7702.345300649424, 7721.7683469496005, 7728.459007869451, 7602.695892164307, 7633.160352361291, 7637.083549974039, 7661.0, 7675.232476752336, 40542.79728601338, 40634.59365406338, 40789.796051019555, 40812.38781418281, 40987.59012409898, 3579.2662504186837, 3617.6201498842506, 3621.800800955946, 3624.619414961444, 3629.6370362963758, 32641.67358326434, 32678.346433071205, 32712.509312360333, 32776.50835237473, 32824.34351312984, 5099.46455622158, 5104.923426148611, 5124.231365295198, 5125.487451254845, 5148.613853960968, 193730.0, 194510.99999999857, 194522.0, 195503.0, 196208.0, 50918.745406272734, 51095.23357149646, 51107.233391499154, 51125.0, 51130.721731957, 52994.20508692373, 53011.469885300656, 53056.0, 53069.73465132727, 53130.0, 16738.58153546171, 17183.226754796066, 17217.827821721872, 17225.56936076608, 17255.91372043157, 12075.637730868088, 12275.93862030684, 13981.580552583438, 14188.361523731457, 14267.500637477639, 14571.0, 14571.92714036423, 14625.26873656313, 14752.704945901021, 14780.187089710169, 5830.737616807254, 5965.522758179335, 5982.341942386334, 6014.8195554133445, 6019.969900150471, 1005.7535903703639, 1012.5848402155228, 1013.9188864890939, 1018.2719355660738, 1030.9175265978704, 23118.844057797043, 23214.76785232126, 23387.41531461693, 23446.765532344798, 23460.296191113954, 1125.071815752008, 1130.649498655403, 1134.478140055567, 1137.3119262846024, 1141.275290190735, 12339.07456940715, 12542.811857821958, 12575.5598554052, 12585.307808070462, 12594.685132871751, 237388.0, 240817.0, 240817.0, 241466.0, 241600.0, 54578.72710636501, 55480.99999999919, 55503.16745248825, 55548.72225638846, 55581.7220913901, 1457.6428774949993, 1507.5929499035199, 1521.5967768541345, 1522.4519173097676, 1525.3983317516563, 22912.4271893204, 22925.541489170457, 22976.885115574314, 22984.080636774343, 23074.423139421648, 38300.6169938297, 38465.0, 38564.0, 38585.0, 38621.6137838618, 195238.0, 196404.0, 196618.0, 196814.0, 197109.0, 34760.82619586886, 34980.82509587436, 36975.44536832003, 37698.43452348272, 37700.62299376971, 46112.0, 47825.0, 48585.0, 48699.026019478704, 48791.268130977354, 34941.301173976884, 35010.0, 35303.64696353004, 35613.821930891216, 35655.465168023016, 35353.646463535035, 35358.823205883804, 35373.82313088418, 35916.82041589775, 36073.458898117075, 39035.41446878242, 39761.0, 40013.1997360057, 40115.39826902657, 40203.0, 33948.151296217795, 34110.317793644484, 34160.658393415746, 34234.48648270328, 34582.48126278057, 24010.51978960446, 24456.510869782862, 24856.254312370656, 24869.129580464596, 25007.874645640626], "value": 17763.45501406937, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.9125935479999, 573.5812856614674, 575.2435547255358, 579.3164066401649, 583.9474447299743, 22268.66597001046, 22632.321030369094, 23465.06139754408, 23467.882660586714, 23550.88224558877, 26600.73399266007, 26964.325891852714, 27110.0, 27170.0, 27185.320366990836, 6430.5177111716575, 6561.179852518435, 6561.606303621798, 6573.868522629545, 6600.306967768363, 6024.2168518092585, 6468.514861385392, 6557.409833115019, 6569.638669873155, 6579.276279609239, 6527.249366322862, 6528.183977002875, 6540.411362977331, 6549.639769812658, 6551.967240163816, 6495.480361571086, 6500.674966251692, 6515.4787616990525, 6525.771597994071, 6534.444572211368, 6053.606515576517, 6070.089486577004, 6070.726817293188, 6077.969610151965, 6088.238970128734, 28919.710802891914, 29999.550006749916, 30624.846875765703, 30790.23024424385, 30919.690803091908, 1884.3593178319438, 1924.7594050743658, 1927.469945764912, 1930.4980705016796, 1931.0730849192466, 19803.801961980338, 19984.300549480842, 21179.0, 21234.362969110796, 21237.256696015564, 2386.5942789725614, 2422.515496900627, 2423.575874222004, 2435.8294919355653, 2535.239428171541, 137898.0, 148583.0, 155223.0, 155317.0, 155415.2229238858, 14728.232612086618, 15169.967779803857, 15370.615734606501, 15745.44890928812, 15996.280167392495, 23850.0, 23867.641985370232, 23925.401864953514, 23970.0, 23973.400664983514, 9645.37305075168, 9687.612495500241, 9893.65372211969, 9976.0, 10663.94704662143, 8843.469391836381, 8858.557072146366, 8893.644254229888, 8928.508932008803, 8937.106289371011, 9229.53852307382, 9273.397229180082, 9457.574409151604, 9463.432194068379, 9848.901510984942, 2733.221032005869, 2734.4941185880675, 2834.6881842997254, 2841.843698596556, 2872.5547540131315, 957.3777044920718, 959.9057074934581, 962.5283611030544, 976.2921881635757, 978.6476868327396, 19664.21343146258, 23757.643635345485, 24253.757462425503, 25385.0, 25581.616275755878, 1054.7890421915495, 1072.5495291977388, 1072.7908057928785, 1075.091547642241, 1078.7411021354976, 11445.771084578428, 11611.187216894905, 11640.0, 11649.650510484698, 11719.238249513754, 530360.0, 534140.0, 545030.0, 546205.0, 553124.0, 121292.0, 123020.0, 125144.37427812803, 125731.3713431427, 126146.0, 1137.2039572299423, 1187.8040123379546, 1250.2186133666457, 1297.941592628334, 1302.0299876592, 21077.6838347428, 21333.0, 21782.891085545103, 21889.12443502306, 22474.101035959033, 57609.71195144165, 58010.0, 59003.70498147482, 59408.70295648494, 60621.0, 64959.35040649533, 65114.34885651083, 67734.0, 67948.6602566984, 68370.65814670894, 15018.924905375403, 15141.015833971198, 15301.923490382476, 15415.306311216247, 15443.536693899201, 24009.759902400754, 24012.87993560021, 24214.0, 24456.6331505024, 24544.140955066487, 18545.165467554263, 18559.35042273514, 18760.0, 18772.155253013374, 18830.717539237197, 16198.919005404898, 17093.658126837643, 17165.485035448957, 17849.0, 18349.908250458662, 18972.146253418352, 20163.59672806565, 20215.29246476366, 20430.795692042888, 20496.69254961147, 14837.851621483647, 15311.693766124392, 15320.157391343582, 15365.6158596036, 15385.230738462807, 18679.252829886653, 18880.905595471933, 19168.52078697988, 19205.231790728772, 19214.8078519213], "value": 12371.407255814402, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.2058066611698, 1446.1395469695528, 1448.493027440396, 1467.9412823487064, 1469.4195792661892, 14075.225862577554, 14732.18972956487, 14832.0, 14843.480478183266, 14846.0, 13705.314734263271, 14071.92964035181, 14827.851721482783, 14892.329845156966, 14919.701605967874, 2591.3910231095724, 2626.0283695032767, 2633.986830065857, 2636.9208923732317, 2642.352623607219, 2559.9744002560014, 2585.28904551249, 2623.22614828626, 2682.919512414631, 2688.2741659751855, 2691.434798692277, 2745.1215611004486, 2746.6978632350424, 2751.610436729449, 2752.628395166657, 2622.357522407003, 2666.173486219259, 2675.705672376037, 2704.3509557706207, 2706.228724813419, 2508.736582658813, 2533.505966336565, 2556.335352808265, 2598.1296265751, 2599.6620439342955, 14212.786808197885, 14214.862810975097, 14251.857481425157, 14263.714725705531, 14269.857301426957, 7159.427245820322, 7388.815279617998, 7403.111626604788, 7425.071866016748, 7430.702771889118, 4747.715137091785, 4815.927761083586, 4973.5523802857915, 5028.874278143076, 5036.06832735945, 1869.0094250047496, 1880.1445342369188, 1881.9529511762178, 1881.9811801881942, 1906.6663333916508, 23816.880915595484, 24170.879145604336, 24218.152364667152, 24426.877865610735, 24459.0, 6411.461249300134, 6500.609963402211, 6620.437262832616, 6732.697028633724, 6773.864522709617, 19021.14404851785, 19039.714404283946, 19039.904800476186, 19051.714224286647, 19105.90447047756, 7372.410207183412, 7659.885101723478, 7662.885056724153, 7686.0, 7700.114486834086, 6841.144856892888, 6914.8617027660175, 6916.100906882073, 6970.546914450544, 7062.364387205176, 5287.709175995357, 5450.291462109901, 5508.476694713996, 5516.806911758069, 5676.148577713376, 1483.9629009274854, 1533.2333833083458, 1658.6433916707965, 1693.5596744846428, 1727.395411605953, 864.1444969480295, 875.9474431534128, 890.3633901760272, 893.5130353957109, 897.7845317123844, 7797.571133587594, 8213.507189568645, 8306.293965013041, 8360.498370097814, 8365.665373385118, 923.2383283790899, 940.5250348574064, 958.3435346787472, 986.7730422002979, 999.8050380175941, 5517.97917385285, 5538.0, 5646.830595082154, 5699.658020518782, 5739.483446489836, 19437.4168774937, 19447.708284375745, 20254.189832406537, 20256.59486810285, 20261.696074558895, 19542.902285488766, 19721.802781972285, 19806.20775168977, 19832.801671983383, 19868.60262794736, 10557.366558006544, 11099.223054386115, 11109.944450277697, 11200.655921289497, 11270.154738394653, 7960.124386317616, 8088.393370497119, 8253.917460825554, 8260.876086858581, 8388.286995605198, 16234.431794887123, 16237.431689890796, 16300.836991629933, 16347.836521635107, 16791.244394002544, 16012.0, 16372.918135409247, 16380.918095409446, 16662.50012499627, 16710.66578668444, 13141.73716525645, 13482.460701571827, 13498.25759583193, 13499.122557033761, 13508.121972072177, 13373.197608143542, 13528.93235533816, 13562.186268823902, 13668.111572747739, 13698.52055178064, 9838.505098524976, 10531.315464494783, 11227.831582526433, 11262.436878155895, 11534.250273732181, 10940.56237750513, 10999.23005389615, 11158.442077896236, 11271.49278282463, 11309.095272378354, 9412.57643406062, 9533.47565883883, 9538.093881081286, 9541.0, 9542.42745435276, 7339.926600733925, 7343.70625174987, 7372.299631534996, 7372.815679608053, 7388.556686598821, 7156.821079473055, 7301.160366557914, 7306.086739157606, 7352.852942941005, 7382.889256661262], "value": 6544.513323772026, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.49459551257706, 499.4431209201741, 504.45266885429305, 507.2543361258948, 507.69269131985124, 6193.81418557444, 6196.59722118062, 6235.1894253747005, 6289.654069026202, 6345.0, 5995.550333724967, 6057.182280392146, 6097.756089756415, 6147.231596050494, 6173.938260617392, 4817.927731084036, 4988.825391111293, 5035.974820125894, 5061.822836200734, 5086.872828179288, 4871.926921096187, 4894.143524883149, 4903.926441103386, 4921.80312787488, 4964.602831773467, 4855.611551075923, 4865.927011094836, 4872.902541949159, 4892.119418504685, 4900.0, 4875.829345972892, 4891.535304146136, 4913.508649135094, 4938.234573641073, 4964.304997300381, 4384.4519435070615, 4403.515613282536, 4406.405135306724, 4417.337399390117, 4430.29115341547, 5933.614315069507, 6088.756449742049, 6096.420840020191, 6128.785492507785, 6148.78479253224, 5575.944240557624, 5589.804356847531, 5625.887482250332, 5638.830835074913, 5684.54523638108, 6547.9345206547805, 6549.279579246279, 6570.671466426707, 6581.617860249354, 6585.736570537173, 4761.59526440253, 4772.76136193189, 4796.424429068534, 4808.807647694088, 4844.418669759616, 6554.70503827329, 6678.6326752028135, 6812.0, 6860.759873404407, 7012.894806577905, 5436.374816896028, 5467.125259958467, 5498.86564658141, 5505.174223866372, 5518.420565840609, 5586.385497595261, 5598.944010559923, 5606.383882395832, 5634.915476267859, 5658.405867383865, 5980.790672326535, 6006.459418652343, 6018.6990650467305, 6033.939660603425, 6034.48706859913, 6652.800415987528, 6703.329667033355, 6749.223839258548, 6762.729490820312, 6764.458843292525, 1943.5626983928785, 1945.747052883116, 1948.4836518322657, 1953.3846838245906, 1980.4949737816764, 1367.282176857158, 1409.3023953143054, 1430.6051599690218, 1431.7709166533514, 1459.6277949122905, 5703.315602127771, 5711.885762284731, 5752.9712351437975, 5767.163761254595, 5868.149118377812, 6619.801405957829, 6639.668016599151, 6720.22717387497, 6791.049253104569, 6829.224401655506, 5747.971260143672, 5803.9419605803405, 5825.650460972355, 5846.795362162304, 5856.0, 3582.2477279771538, 3589.300086483163, 3642.3625865473437, 3659.9634003660153, 3675.46705727668, 6517.576357536745, 6530.640814755234, 6562.868742625121, 6586.934130658728, 6631.3700198481065, 6823.283555226729, 6876.177812879591, 6901.585904845725, 6913.861722765517, 6953.200381956039, 6621.702023408958, 6623.569467984566, 6655.567388119756, 6659.9001014984815, 6661.235587231342, 6807.659617019228, 6818.249992500921, 6853.828654283683, 6862.931370686429, 6898.757903681223, 6693.129893114059, 6712.731490740316, 6726.630035348103, 6740.4496965699145, 6742.6291553965, 6658.966705166443, 6663.733450662113, 6674.466042716474, 6676.332366763285, 6681.832954176184, 5490.505854472957, 5514.889702206014, 5531.8893622128135, 5533.446655334435, 5554.833354999357, 6503.512236582181, 6627.1716035495565, 6734.225564060196, 6866.283429142714, 6915.723371065101, 6555.901661474986, 6562.442192413699, 6570.178727659043, 6573.638449885302, 6609.537332386879, 6595.241547222131, 6598.769043083661, 6613.173353330833, 6633.800985970429, 6660.433863121689, 6290.308066112632, 6402.431404305975, 6433.678316084271, 6513.478921686159, 6544.3311955452955, 6466.547341686037, 6565.096122124531, 6582.341765823379, 6607.636579988147, 6613.735450581923, 6291.182146320949, 6364.268109167319, 6381.8723625528155, 6425.293217745951, 6469.320721324193], "value": 5147.721006829908, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [874.1346067393279, 889.719738282441, 900.3337530227625, 900.9549522523872, 910.4946754551225, 18631.5342116447, 19913.3030343938, 19924.302649407273, 19979.0, 20063.398098057045, 16234.350625974976, 16308.510744677678, 17650.646987060252, 22146.88926555365, 22167.44581385466, 1047.83234682451, 1073.1200415659162, 1073.538378497246, 1073.8550295710097, 1073.8979796919318, 1007.9899201007969, 1053.2364036073823, 1053.441675911768, 1055.6305293147414, 1068.4283908109164, 1123.2642619084472, 1125.431657013207, 1125.8367536707171, 1125.8930401611833, 1126.2003977176214, 1125.0999200639455, 1126.0147371050332, 1127.3010733345297, 1128.0129886349403, 1130.513879032016, 819.7581713394563, 820.9343252539783, 821.2116368286452, 823.5305875650881, 825.2449009156614, 19917.0, 20105.39683809488, 20140.19439222444, 20320.28878989228, 20892.686609700868, 21554.245601404033, 21634.675479867816, 21981.67027494589, 22303.442413939618, 22455.887720561455, 16761.83238167627, 16779.664406711796, 17159.485215443558, 17165.828341716548, 17240.396586119427, 3631.092226943264, 3642.908927276813, 3642.908927276813, 3649.069487280753, 3654.26914617075, 130446.0, 130856.34571827175, 131423.34288328496, 131774.0, 135209.32395337961, 16933.661326773643, 17128.0, 17183.57041073983, 17396.0, 17408.303667853397, 30313.0, 31321.0, 31876.521852172235, 32104.83947580247, 32159.678403216138, 8750.781230469162, 8900.154485323885, 9003.459792412476, 9034.954825225963, 9142.131497507726, 3937.527496700357, 4090.550039495653, 4092.6112019358116, 4093.91812163761, 4103.835846566164, 5183.97408012965, 5264.289320941701, 5264.473552644781, 5293.153095504777, 5321.228421878806, 970.7476056225291, 975.7365511311906, 992.1071036067452, 997.8253805584139, 998.4408731110601, 682.74738346812, 685.3626127701285, 691.2672567078912, 703.7501686901179, 705.4955706669756, 11804.704882377837, 11932.0, 11937.88062119385, 12415.379231038412, 12505.187162834387, 680.452235950065, 698.179638924262, 708.348319546019, 709.1738125084322, 720.5280541245493, 5432.456754324536, 5529.695866727368, 5548.611597188239, 5549.334079910356, 5650.039493286151, 110711.0, 111303.0, 112739.0, 113265.4336728311, 114219.0, 97037.0, 97981.0, 98804.0, 98898.53855115037, 99849.0, 86729.0, 89788.0, 89881.0, 92134.07865921255, 92536.53731731433, 12337.50649973991, 12777.297248651414, 12806.295653739135, 13013.414396351998, 13019.414126364525, 39364.0, 39531.20937581175, 40438.59561404466, 41955.0, 42137.36793948032, 71701.28298716946, 72381.6380918092, 73520.26479735348, 74279.62860185665, 78969.60515197387, 18647.160877760805, 19293.324733634254, 19574.11916463734, 19972.300969466, 20171.193152273743, 22266.443338916655, 22313.107475700792, 22638.320850374515, 22642.43393915165, 23004.07983680634, 19025.71461428052, 19220.80779192246, 19620.90189549043, 20050.298239561544, 20246.392608221777, 19221.807781922, 19311.324103656305, 19418.320358787376, 19421.708674370177, 19512.414627561197, 17776.91111544434, 18171.818281817013, 18367.0, 18386.0, 18438.35465758692, 13921.512747053805, 13961.650958725706, 14048.859511404755, 14110.365033573718, 14144.646383840487, 14703.852961470247, 15008.924955375152, 15130.0, 15343.846561534241, 15648.217589120726], "value": 9430.455431770239, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 108.0, 110.0, 108.0, 108.0, 109.0, 101.0, 114.0, 109.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 142.0, 169.0, 121.0, 149.0, 147.0, 123.0, 168.0, 124.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 164.0, 161.0, 162.0, 163.0, 165.0, 168.0, 170.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 76.0, 173.0, 79.0, 85.0, 79.0, 83.0, 98.0, 76.0], "value": 90.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 127.0, 78.0, 81.0, 79.0, 125.0, 80.0, 80.0, 139.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 114.0, 115.0, 114.0, 114.0, 117.0, 119.0, 115.0, 116.0, 115.0], "value": 115.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 152.0, 151.0, 152.0, 154.0, 151.0, 150.0, 150.0, 151.0], "value": 151.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 101.0, 101.0, 95.0, 95.0, 94.0, 94.0, 94.0, 99.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 56.0, 53.0, 54.0, 53.0, 53.0, 53.0, 53.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 54.0, 54.0, 62.0, 54.0, 54.0, 54.0, 55.0, 55.0, 54.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 77.0, 75.0, 77.0, 77.0, 76.0, 78.0, 81.0], "value": 77.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 131.0, 124.0, 131.0, 119.0, 122.0, 121.0, 125.0, 173.0], "value": 129.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 177.0, 183.0, 180.0, 174.0, 183.0, 178.0, 194.0, 175.0, 179.0], "value": 181.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 70.0, 69.0, 68.0, 68.0, 71.0, 71.0, 70.0, 70.0], "value": 69.4, "unit": "score"}]}]} 11:23:03 INFO - Return code: 0 11:23:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:23:03 INFO - # TBPL SUCCESS # 11:23:03 INFO - Running post-action listener: _package_coverage_data 11:23:03 INFO - Running post-action listener: _resource_record_post_action 11:23:03 INFO - [mozharness: 2017-08-16 18:23:03.099507Z] Finished run-tests step (success) 11:23:03 INFO - Running post-run listener: _resource_record_post_run 11:23:03 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 435396608; Read time: 20; Write time: 293328 11:23:03 INFO - TinderboxPrint: CPU usage
13.1% 11:23:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 11:23:03 INFO - TinderboxPrint: I/O write bytes / time
435,396,608 / 293,328 11:23:03 INFO - TinderboxPrint: CPU idle
8,261.2 (86.9%) 11:23:03 INFO - TinderboxPrint: CPU user
1,200.7 (12.6%) 11:23:03 INFO - TinderboxPrint: Swap in / out
0 / 0 11:23:03 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 110661632; Read time: 0; Write time: 96540 11:23:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:23:03 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 324734976; Read time: 0; Write time: 196788 11:23:03 INFO - Running post-run listener: _upload_blobber_files 11:23:03 INFO - Blob upload gear active. 11:23:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:23:03 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:23:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:23:04 INFO - (blobuploader) - INFO - Open directory for files ... 11:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:23:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:04 INFO - (blobuploader) - INFO - Done attempting. 11:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:23:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:11 INFO - (blobuploader) - INFO - Done attempting. 11:23:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:23:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:23:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:12 INFO - (blobuploader) - INFO - Done attempting. 11:23:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:23:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:23:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:23:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:23:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:23:13 INFO - (blobuploader) - INFO - Done attempting. 11:23:13 INFO - (blobuploader) - INFO - Iteration through files over. 11:23:13 INFO - Return code: 0 11:23:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:23:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:23:13 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b658f61ceb74020c1342f7df609f8c7f110438b5af36e595ebc28eed62dda36e31c9a77ac8cc8b04f5f263fb0e96744140e09d712449cb143eb3505b5ae5a392", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9291506986f1b71d0cc569782e614bef7ef1a34415f48048414bd8e4f8470b9e4160894e2ae4693c1e52ff6f6d956eb67644a338a5414cee3e247c6648de6597", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd2862b718e2ab8073eed5f0fc8d5193869df56cc61ddefbf1da48f225c9255deebb7937f06f34f2890435a660f6d8b9e49f6943b47132d49ea682877d81e825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/010e5c0fed6bb716a06b7f777634f81b94b7aa712c5d32ad9104fa45310436ef4e410d2b6e40d3f2035148c1170cf92ee7a684f4b0d9680beb018111f5501572"} 11:23:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:23:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:23:13 INFO - Contents: 11:23:13 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b658f61ceb74020c1342f7df609f8c7f110438b5af36e595ebc28eed62dda36e31c9a77ac8cc8b04f5f263fb0e96744140e09d712449cb143eb3505b5ae5a392", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9291506986f1b71d0cc569782e614bef7ef1a34415f48048414bd8e4f8470b9e4160894e2ae4693c1e52ff6f6d956eb67644a338a5414cee3e247c6648de6597", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd2862b718e2ab8073eed5f0fc8d5193869df56cc61ddefbf1da48f225c9255deebb7937f06f34f2890435a660f6d8b9e49f6943b47132d49ea682877d81e825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/010e5c0fed6bb716a06b7f777634f81b94b7aa712c5d32ad9104fa45310436ef4e410d2b6e40d3f2035148c1170cf92ee7a684f4b0d9680beb018111f5501572"} 11:23:13 INFO - Running post-run listener: copy_logs_to_upload_dir 11:23:13 INFO - Copying logs to upload dir... 11:23:13 INFO - mkdir: /builds/slave/test/build/upload/logs 11:23:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.304022 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-08-16 11:23:13.403416) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 11:23:13.406580) ========= 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=1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b658f61ceb74020c1342f7df609f8c7f110438b5af36e595ebc28eed62dda36e31c9a77ac8cc8b04f5f263fb0e96744140e09d712449cb143eb3505b5ae5a392", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9291506986f1b71d0cc569782e614bef7ef1a34415f48048414bd8e4f8470b9e4160894e2ae4693c1e52ff6f6d956eb67644a338a5414cee3e247c6648de6597", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd2862b718e2ab8073eed5f0fc8d5193869df56cc61ddefbf1da48f225c9255deebb7937f06f34f2890435a660f6d8b9e49f6943b47132d49ea682877d81e825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/010e5c0fed6bb716a06b7f777634f81b94b7aa712c5d32ad9104fa45310436ef4e410d2b6e40d3f2035148c1170cf92ee7a684f4b0d9680beb018111f5501572"} 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.011284 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/sha512/b658f61ceb74020c1342f7df609f8c7f110438b5af36e595ebc28eed62dda36e31c9a77ac8cc8b04f5f263fb0e96744140e09d712449cb143eb3505b5ae5a392", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9291506986f1b71d0cc569782e614bef7ef1a34415f48048414bd8e4f8470b9e4160894e2ae4693c1e52ff6f6d956eb67644a338a5414cee3e247c6648de6597", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd2862b718e2ab8073eed5f0fc8d5193869df56cc61ddefbf1da48f225c9255deebb7937f06f34f2890435a660f6d8b9e49f6943b47132d49ea682877d81e825", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/010e5c0fed6bb716a06b7f777634f81b94b7aa712c5d32ad9104fa45310436ef4e410d2b6e40d3f2035148c1170cf92ee7a684f4b0d9680beb018111f5501572"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-16 11:23:13.435513) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:23:13.435868) ========= 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=1f5564757af27664ca02d71f000001b0-1502906536.74462-484325174 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004435 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-16 11:23:13.518984) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 11:23:13.522328) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-16 11:23:13.522614) ========= ========= Total master_lag: 0.32 =========