builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-012 starttime: 1502109438.05 results: success (0) revision: 8437bfefc8417d057015e8b4d77b6a5c67360863 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:18.045691) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:18.046336) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:18.046638) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:18.068419) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:18.068735) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-07 05:37: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% 37.8M=0s 2017-08-07 05:37:18 (37.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.397588 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:18.492556) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:18.492869) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014224 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:18.553255) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-07 05:37:18.556611) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8437bfefc8417d057015e8b4d77b6a5c67360863 --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 8437bfefc8417d057015e8b4d77b6a5c67360863 --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=2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-07 05:37:18,420 truncating revision to first 12 chars 2017-08-07 05:37:18,420 Setting DEBUG logging. 2017-08-07 05:37:18,420 attempt 1/10 2017-08-07 05:37:18,420 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8437bfefc841?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-07 05:37:18,910 unpacking tar archive at: mozilla-central-8437bfefc841/testing/mozharness/ program finished with exit code 0 elapsedTime=0.917921 ========= master_lag: 0.49 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-07 05:37:19.964597) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:19.964881) ========= script_repo_revision: 8437bfefc8417d057015e8b4d77b6a5c67360863 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:19.965254) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:19.965531) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-07 05:37:20.004469) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-07 05:37:20.004751) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:37:19 INFO - MultiFileLogger online at 20170807 05:37:19 in /builds/slave/test 05:37:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:37:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:37:19 INFO - {'append_to_log': False, 05:37:19 INFO - 'base_work_dir': '/builds/slave/test', 05:37:19 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:37:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:37:19 INFO - 'branch': 'Firefox-Non-PGO', 05:37:19 INFO - 'buildbot_json_path': 'buildprops.json', 05:37:19 INFO - 'code_coverage': False, 05:37:19 INFO - 'config_files': ('talos/linux_config.py',), 05:37:19 INFO - 'default_actions': ('clobber', 05:37:19 INFO - 'read-buildbot-config', 05:37:19 INFO - 'download-and-extract', 05:37:19 INFO - 'populate-webroot', 05:37:19 INFO - 'create-virtualenv', 05:37:19 INFO - 'install', 05:37:19 INFO - 'setup-mitmproxy', 05:37:19 INFO - 'run-tests'), 05:37:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:37:19 INFO - 'disable_ccov_upload': False, 05:37:19 INFO - 'download_minidump_stackwalk': True, 05:37:19 INFO - 'download_symbols': 'ondemand', 05:37:19 INFO - 'enable_stylo': False, 05:37:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:37:19 INFO - 'tooltool.py': '/tools/tooltool.py', 05:37:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:37:19 INFO - '/tools/misc-python/virtualenv.py')}, 05:37:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:37:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:37:19 INFO - 'gecko_profile': False, 05:37:19 INFO - 'gecko_profile_interval': 0, 05:37:19 INFO - 'installer_path': 'installer.exe', 05:37:19 INFO - 'log_level': 'info', 05:37:19 INFO - 'log_name': 'talos', 05:37:19 INFO - 'log_to_console': True, 05:37:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:37:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:37:19 INFO - 'opt_config_files': (), 05:37:19 INFO - 'pip_index': False, 05:37:19 INFO - 'suite': 'dromaeojs-e10s', 05:37:19 INFO - 'system_bits': '32', 05:37:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:37:19 INFO - 'title': 'talos-linux64-ix-012', 05:37:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:37:19 INFO - 'use_talos_json': True, 05:37:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:37:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:37:19 INFO - 'work_dir': 'build'} 05:37:19 INFO - [mozharness: 2017-08-07 12:37:19.971170Z] Running clobber step. 05:37:19 INFO - Running pre-action listener: _resource_record_pre_action 05:37:19 INFO - Running main action method: clobber 05:37:19 INFO - rmtree: /builds/slave/test/build 05:37:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:37:21 INFO - Running post-action listener: _resource_record_post_action 05:37:21 INFO - [mozharness: 2017-08-07 12:37:21.007823Z] Finished clobber step (success) 05:37:21 INFO - [mozharness: 2017-08-07 12:37:21.007927Z] Running read-buildbot-config step. 05:37:21 INFO - Running pre-action listener: _resource_record_pre_action 05:37:21 INFO - Running main action method: read_buildbot_config 05:37:21 INFO - Using buildbot properties: 05:37:21 INFO - { 05:37:21 INFO - "project": "", 05:37:21 INFO - "product": "firefox", 05:37:21 INFO - "who": "cbook@mozilla.com", 05:37:21 INFO - "installer_path": "public/build/target.tar.bz2", 05:37:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:37:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:37:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 05:37:21 INFO - "stage_platform": "linux64", 05:37:21 INFO - "basedir": "/builds/slave/test", 05:37:21 INFO - "buildnumber": 761, 05:37:21 INFO - "platform": "ubuntu64_hw", 05:37:21 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:37:21 INFO - "slavebuilddir": "test", 05:37:21 INFO - "taskId": "ErCBvygLS6y551VJIGnKGQ", 05:37:21 INFO - "branch": "mozilla-central", 05:37:21 INFO - "script_repo_revision": "production", 05:37:21 INFO - "revision": "8437bfefc8417d057015e8b4d77b6a5c67360863", 05:37:21 INFO - "slavename": "talos-linux64-ix-012", 05:37:21 INFO - "repo_path": "mozilla-central" 05:37:21 INFO - } 05:37:21 INFO - Finding installer, test and symbols from parent task. 05:37:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ErCBvygLS6y551VJIGnKGQ'}, attempt #1 05:37:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ErCBvygLS6y551VJIGnKGQ'}, attempt #1 05:37:21 INFO - Task dependencies: CTkm8jlKQvKlvnvlEGpMow 05:37:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow'}, attempt #1 05:37:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.tar.bz2 05:37:22 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.test_packages.json 05:37:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.crashreporter-symbols.zip 05:37:22 INFO - Running post-action listener: _resource_record_post_action 05:37:22 INFO - [mozharness: 2017-08-07 12:37:22.384031Z] Finished read-buildbot-config step (success) 05:37:22 INFO - [mozharness: 2017-08-07 12:37:22.384251Z] Running download-and-extract step. 05:37:22 INFO - Running pre-action listener: _resource_record_pre_action 05:37:22 INFO - Running main action method: download_and_extract 05:37:22 INFO - mkdir: /builds/slave/test/build/tests 05:37: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')]} 05:37:22 INFO - trying https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.test_packages.json 05:37:22 INFO - Downloading https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:37:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:37:22 INFO - Downloaded 989 bytes. 05:37:22 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:37:22 INFO - Using the following test package requirements: 05:37:22 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:37:22 INFO - u'common': [u'target.common.tests.zip'], 05:37:22 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:37:22 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:37:22 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:37:22 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:37:22 INFO - u'mozbase': [u'target.common.tests.zip'], 05:37:22 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:37:22 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:37:22 INFO - u'web-platform': [u'target.common.tests.zip', 05:37:22 INFO - u'target.web-platform.tests.tar.gz'], 05:37:22 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:37:22 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:37:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.common.tests.zip 05:37:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:37:22 INFO - Fetch https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.common.tests.zip into memory 05:37:24 INFO - Content-Length response header: 54284820 05:37:24 INFO - Bytes received: 54284820 05:37:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:37:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.common.tests.zip 05:37:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:37:35 INFO - Fetch https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.common.tests.zip into memory 05:37:36 INFO - Content-Length response header: 54284820 05:37:36 INFO - Bytes received: 54284820 05:37:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.talos.tests.zip 05:37:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:37:48 INFO - Fetch https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.talos.tests.zip into memory 05:37:49 INFO - Content-Length response header: 14496008 05:37:49 INFO - Bytes received: 14496008 05:37:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:49 INFO - trying https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.tar.bz2 05:37:49 INFO - Downloading https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:37:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:37:52 INFO - Downloaded 61784750 bytes. 05:37:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.tar.bz2 05:37:52 INFO - mkdir: /builds/slave/test/properties 05:37:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:37:52 INFO - Writing to file /builds/slave/test/properties/build_url 05:37:52 INFO - Contents: 05:37:52 INFO - build_url:https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.tar.bz2 05:37:52 INFO - Running post-action listener: _resource_record_post_action 05:37:52 INFO - Running post-action listener: set_extra_try_arguments 05:37:52 INFO - [mozharness: 2017-08-07 12:37:52.331105Z] Finished download-and-extract step (success) 05:37:52 INFO - [mozharness: 2017-08-07 12:37:52.331333Z] Running populate-webroot step. 05:37:52 INFO - Running pre-action listener: _resource_record_pre_action 05:37:52 INFO - Running main action method: populate_webroot 05:37:52 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:37:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:37:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:37:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:37:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:37:52 INFO - 'tests': ['damp', 'tps']}, 05:37:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:37:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:37:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:37:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:37:52 INFO - 'other-e10s': {'tests': ['a11yr', 05:37:52 INFO - 'ts_paint', 05:37:52 INFO - 'tpaint', 05:37:52 INFO - 'sessionrestore', 05:37:52 INFO - 'sessionrestore_many_windows', 05:37:52 INFO - 'sessionrestore_no_auto_restore', 05:37:52 INFO - 'tabpaint']}, 05:37:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:37:52 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:37:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:37:52 INFO - 'tsvgr_opacity', 05:37:52 INFO - 'tart', 05:37:52 INFO - 'tscrollx', 05:37:52 INFO - 'tsvg_static']}, 05:37:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:37:52 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:37:52 INFO - 'talos_options': ['--mitmproxy', 05:37:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:37:52 INFO - '--firstNonBlankPaint'], 05:37:52 INFO - 'tests': ['tp6_google', 05:37:52 INFO - 'tp6_youtube', 05:37:52 INFO - 'tp6_amazon', 05:37:52 INFO - 'tp6_facebook']}, 05:37:52 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:37:52 INFO - 'talos_options': ['--stylo', 05:37:52 INFO - '--mitmproxy', 05:37:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:37:52 INFO - '--firstNonBlankPaint'], 05:37:52 INFO - 'tests': ['tp6_google', 05:37:52 INFO - 'tp6_youtube', 05:37:52 INFO - 'tp6_amazon', 05:37:52 INFO - 'tp6_facebook']}, 05:37:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:37:52 INFO - 'talos_options': ['--stylo', 05:37:52 INFO - '--stylo-threads=1', 05:37:52 INFO - '--mitmproxy', 05:37:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:37:52 INFO - '--firstNonBlankPaint'], 05:37:52 INFO - 'tests': ['tp6_google', 05:37:52 INFO - 'tp6_youtube', 05:37:52 INFO - 'tp6_amazon', 05:37:52 INFO - 'tp6_facebook']}, 05:37:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:37:52 INFO - 'talos_options': ['--xperf_path', 05:37:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:37:52 INFO - 'tests': ['tp5n']}}} 05:37:52 INFO - Running post-action listener: _resource_record_post_action 05:37:52 INFO - [mozharness: 2017-08-07 12:37:52.340984Z] Finished populate-webroot step (success) 05:37:52 INFO - [mozharness: 2017-08-07 12:37:52.341074Z] Running create-virtualenv step. 05:37:52 INFO - Running pre-action listener: _resource_record_pre_action 05:37:52 INFO - Running main action method: create_virtualenv 05:37:52 INFO - Creating virtualenv /builds/slave/test/build/venv 05:37:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:37:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:37:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:37:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:37:52 INFO - Using real prefix '/usr' 05:37:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:37:52 INFO - Installing distribute.............................................................................................................................................................................................done. 05:37:54 INFO - Installing pip.................done. 05:37:54 INFO - Return code: 0 05:37:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:37:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:37:55 INFO - Reading from file tmpfile_stdout 05:37:55 INFO - Output received: 05:37:55 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:37:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:37: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')]} 05:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37: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')]} 05:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37: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', 'pip>=1.5'] in /builds/slave/test/build 05:37: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 pip>=1.5 05:37:55 INFO - Using env: {'DISPLAY': ':0', 05:37:55 INFO - 'HOME': '/home/cltbld', 05:37:55 INFO - 'LANG': 'en_US.UTF-8', 05:37:55 INFO - 'LANGUAGE': 'en_US:en', 05:37:55 INFO - 'LOGNAME': 'cltbld', 05:37:55 INFO - 'MAIL': '/var/mail/cltbld', 05:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:55 INFO - 'MOZ_NO_REMOTE': '1', 05:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:55 INFO - 'NO_EM_RESTART': '1', 05:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:55 INFO - 'PWD': '/builds/slave/test', 05:37:55 INFO - 'SHELL': '/bin/bash', 05:37:55 INFO - 'SHLVL': '1', 05:37:55 INFO - 'TERM': 'linux', 05:37:55 INFO - 'TMOUT': '86400', 05:37:55 INFO - 'USER': 'cltbld', 05:37:55 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:55 INFO - '_': '/tools/buildbot/bin/python'} 05:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:37:55 INFO - Cleaning up... 05:37:55 INFO - Return code: 0 05:37:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:37: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')]} 05:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37: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')]} 05:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37: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', '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 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:37: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 psutil>=3.1.1 05:37:55 INFO - Using env: {'DISPLAY': ':0', 05:37:55 INFO - 'HOME': '/home/cltbld', 05:37:55 INFO - 'LANG': 'en_US.UTF-8', 05:37:55 INFO - 'LANGUAGE': 'en_US:en', 05:37:55 INFO - 'LOGNAME': 'cltbld', 05:37:55 INFO - 'MAIL': '/var/mail/cltbld', 05:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:55 INFO - 'MOZ_NO_REMOTE': '1', 05:37:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:55 INFO - 'NO_EM_RESTART': '1', 05:37:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:55 INFO - 'PWD': '/builds/slave/test', 05:37:55 INFO - 'SHELL': '/bin/bash', 05:37:55 INFO - 'SHLVL': '1', 05:37:55 INFO - 'TERM': 'linux', 05:37:55 INFO - 'TMOUT': '86400', 05:37:55 INFO - 'USER': 'cltbld', 05:37:55 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:55 INFO - '_': '/tools/buildbot/bin/python'} 05:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:55 INFO - Downloading/unpacking psutil>=3.1.1 05:37: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 05:37: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 05:37: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 05:37: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 05:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:37:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:56 INFO - Installing collected packages: psutil 05:37:56 INFO - Running setup.py install for psutil 05:37:56 INFO - building 'psutil._psutil_linux' extension 05:37:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:37:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:37:57 INFO - building 'psutil._psutil_posix' extension 05:37:57 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:37:57 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:37:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:37:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:37:57 INFO - Successfully installed psutil 05:37:57 INFO - Cleaning up... 05:37:57 INFO - Return code: 0 05:37:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:37:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:37:57 INFO - Using env: {'DISPLAY': ':0', 05:37:57 INFO - 'HOME': '/home/cltbld', 05:37:57 INFO - 'LANG': 'en_US.UTF-8', 05:37:57 INFO - 'LANGUAGE': 'en_US:en', 05:37:57 INFO - 'LOGNAME': 'cltbld', 05:37:57 INFO - 'MAIL': '/var/mail/cltbld', 05:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:57 INFO - 'MOZ_NO_REMOTE': '1', 05:37:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:57 INFO - 'NO_EM_RESTART': '1', 05:37:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:57 INFO - 'PWD': '/builds/slave/test', 05:37:57 INFO - 'SHELL': '/bin/bash', 05:37:57 INFO - 'SHLVL': '1', 05:37:57 INFO - 'TERM': 'linux', 05:37:57 INFO - 'TMOUT': '86400', 05:37:57 INFO - 'USER': 'cltbld', 05:37:57 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:57 INFO - '_': '/tools/buildbot/bin/python'} 05:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:37: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 05:37: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 05:37: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 05:37: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 05:37:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:37:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:37:58 INFO - Installing collected packages: mozsystemmonitor 05:37:58 INFO - Running setup.py install for mozsystemmonitor 05:37:58 INFO - Successfully installed mozsystemmonitor 05:37:58 INFO - Cleaning up... 05:37:58 INFO - Return code: 0 05:37:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:37: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')]} 05:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:37: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')]} 05:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:37: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', '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 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:37: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:37: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 jsonschema==2.5.1 05:37:58 INFO - Using env: {'DISPLAY': ':0', 05:37:58 INFO - 'HOME': '/home/cltbld', 05:37:58 INFO - 'LANG': 'en_US.UTF-8', 05:37:58 INFO - 'LANGUAGE': 'en_US:en', 05:37:58 INFO - 'LOGNAME': 'cltbld', 05:37:58 INFO - 'MAIL': '/var/mail/cltbld', 05:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:37:58 INFO - 'MOZ_NO_REMOTE': '1', 05:37:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:37:58 INFO - 'NO_EM_RESTART': '1', 05:37:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:37:58 INFO - 'PWD': '/builds/slave/test', 05:37:58 INFO - 'SHELL': '/bin/bash', 05:37:58 INFO - 'SHLVL': '1', 05:37:58 INFO - 'TERM': 'linux', 05:37:58 INFO - 'TMOUT': '86400', 05:37:58 INFO - 'USER': 'cltbld', 05:37:58 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:37:58 INFO - '_': '/tools/buildbot/bin/python'} 05:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:37:59 INFO - Downloading/unpacking jsonschema==2.5.1 05:37: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 05:37: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 05:37: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 05:37: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 05:38:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:38:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:38: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 05:38: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 05:38: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 05:38: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 05:38:00 INFO - Downloading functools32-3.2.3-2.tar.gz 05:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:38:00 INFO - warning: no files found matching '*.txt' 05:38:00 INFO - no previously-included directories found matching 'build' 05:38:00 INFO - no previously-included directories found matching 'dist' 05:38:00 INFO - no previously-included directories found matching '.git*' 05:38:00 INFO - Installing collected packages: jsonschema, functools32 05:38:00 INFO - Running setup.py install for functools32 05:38:00 INFO - warning: no files found matching '*.txt' 05:38:00 INFO - no previously-included directories found matching 'build' 05:38:00 INFO - no previously-included directories found matching 'dist' 05:38:00 INFO - no previously-included directories found matching '.git*' 05:38:00 INFO - Successfully installed jsonschema functools32 05:38:00 INFO - Cleaning up... 05:38:00 INFO - Return code: 0 05:38:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:00 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 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:38:00 INFO - Using env: {'DISPLAY': ':0', 05:38:00 INFO - 'HOME': '/home/cltbld', 05:38:00 INFO - 'LANG': 'en_US.UTF-8', 05:38:00 INFO - 'LANGUAGE': 'en_US:en', 05:38:00 INFO - 'LOGNAME': 'cltbld', 05:38:00 INFO - 'MAIL': '/var/mail/cltbld', 05:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:00 INFO - 'MOZ_NO_REMOTE': '1', 05:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:00 INFO - 'NO_EM_RESTART': '1', 05:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:00 INFO - 'PWD': '/builds/slave/test', 05:38:00 INFO - 'SHELL': '/bin/bash', 05:38:00 INFO - 'SHLVL': '1', 05:38:00 INFO - 'TERM': 'linux', 05:38:00 INFO - 'TMOUT': '86400', 05:38:00 INFO - 'USER': 'cltbld', 05:38:00 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:00 INFO - '_': '/tools/buildbot/bin/python'} 05:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:38:00 INFO - Cleaning up... 05:38:00 INFO - Return code: 0 05:38:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:00 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 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:38:00 INFO - Using env: {'DISPLAY': ':0', 05:38:00 INFO - 'HOME': '/home/cltbld', 05:38:00 INFO - 'LANG': 'en_US.UTF-8', 05:38:00 INFO - 'LANGUAGE': 'en_US:en', 05:38:00 INFO - 'LOGNAME': 'cltbld', 05:38:00 INFO - 'MAIL': '/var/mail/cltbld', 05:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:00 INFO - 'MOZ_NO_REMOTE': '1', 05:38:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:00 INFO - 'NO_EM_RESTART': '1', 05:38:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:00 INFO - 'PWD': '/builds/slave/test', 05:38:00 INFO - 'SHELL': '/bin/bash', 05:38:00 INFO - 'SHLVL': '1', 05:38:00 INFO - 'TERM': 'linux', 05:38:00 INFO - 'TMOUT': '86400', 05:38:00 INFO - 'USER': 'cltbld', 05:38:00 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:00 INFO - '_': '/tools/buildbot/bin/python'} 05:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:00 INFO - Downloading/unpacking blobuploader==1.2.4 05:38: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 05:38: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 05:38: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 05:38: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 05:38:02 INFO - Downloading blobuploader-1.2.4.tar.gz 05:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:38:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:38:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:02 INFO - Downloading docopt-0.6.1.tar.gz 05:38:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:38:02 INFO - Installing collected packages: blobuploader, requests, docopt 05:38:02 INFO - Running setup.py install for blobuploader 05:38:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:38:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:38:02 INFO - Running setup.py install for requests 05:38:03 INFO - Running setup.py install for docopt 05:38:03 INFO - Successfully installed blobuploader requests docopt 05:38:03 INFO - Cleaning up... 05:38:03 INFO - Return code: 0 05:38:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:03 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 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:03 INFO - Using env: {'DISPLAY': ':0', 05:38:03 INFO - 'HOME': '/home/cltbld', 05:38:03 INFO - 'LANG': 'en_US.UTF-8', 05:38:03 INFO - 'LANGUAGE': 'en_US:en', 05:38:03 INFO - 'LOGNAME': 'cltbld', 05:38:03 INFO - 'MAIL': '/var/mail/cltbld', 05:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:03 INFO - 'MOZ_NO_REMOTE': '1', 05:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:03 INFO - 'NO_EM_RESTART': '1', 05:38:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:03 INFO - 'PWD': '/builds/slave/test', 05:38:03 INFO - 'SHELL': '/bin/bash', 05:38:03 INFO - 'SHLVL': '1', 05:38:03 INFO - 'TERM': 'linux', 05:38:03 INFO - 'TMOUT': '86400', 05:38:03 INFO - 'USER': 'cltbld', 05:38:03 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:03 INFO - '_': '/tools/buildbot/bin/python'} 05:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:03 INFO - Running setup.py (path:/tmp/pip-uxGVfM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:03 INFO - Running setup.py (path:/tmp/pip-EnBcE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:03 INFO - Running setup.py (path:/tmp/pip-UBOONR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:03 INFO - Running setup.py (path:/tmp/pip-VE9ZYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:03 INFO - Running setup.py (path:/tmp/pip-Tk4Dw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:03 INFO - Running setup.py (path:/tmp/pip-uHzfrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:03 INFO - Running setup.py (path:/tmp/pip-yc6KfK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:03 INFO - Running setup.py (path:/tmp/pip-V5Ngng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:04 INFO - Running setup.py (path:/tmp/pip-jgJ7zJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:04 INFO - Running setup.py (path:/tmp/pip-NzVdz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:04 INFO - Running setup.py (path:/tmp/pip-cQTduO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:04 INFO - Running setup.py (path:/tmp/pip-1QoAT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:04 INFO - Running setup.py (path:/tmp/pip-NhaK1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:04 INFO - Running setup.py (path:/tmp/pip-YQTW82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:04 INFO - Running setup.py (path:/tmp/pip-b7S3_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:04 INFO - Running setup.py (path:/tmp/pip-GvnoPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:04 INFO - Running setup.py (path:/tmp/pip-xxfiM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:38:04 INFO - Running setup.py install for manifestparser 05:38:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:38:04 INFO - Running setup.py install for mozcrash 05:38:04 INFO - Running setup.py install for mozdebug 05:38:05 INFO - Running setup.py install for mozdevice 05:38:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozfile 05:38:05 INFO - Running setup.py install for mozhttpd 05:38:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozinfo 05:38:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozInstall 05:38:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozleak 05:38:05 INFO - Running setup.py install for mozlog 05:38:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for moznetwork 05:38:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:38:05 INFO - Running setup.py install for mozprocess 05:38:05 INFO - Running setup.py install for mozprofile 05:38:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:38:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:38:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:38:06 INFO - Running setup.py install for mozrunner 05:38:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:38:06 INFO - Running setup.py install for mozscreenshot 05:38:06 INFO - Running setup.py install for moztest 05:38:06 INFO - Running setup.py install for mozversion 05:38:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:38:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:38:06 INFO - Cleaning up... 05:38:06 INFO - Return code: 0 05:38:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:06 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 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:38:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:06 INFO - Using env: {'DISPLAY': ':0', 05:38:06 INFO - 'HOME': '/home/cltbld', 05:38:06 INFO - 'LANG': 'en_US.UTF-8', 05:38:06 INFO - 'LANGUAGE': 'en_US:en', 05:38:06 INFO - 'LOGNAME': 'cltbld', 05:38:06 INFO - 'MAIL': '/var/mail/cltbld', 05:38:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:06 INFO - 'MOZ_NO_REMOTE': '1', 05:38:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:06 INFO - 'NO_EM_RESTART': '1', 05:38:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:06 INFO - 'PWD': '/builds/slave/test', 05:38:06 INFO - 'SHELL': '/bin/bash', 05:38:06 INFO - 'SHLVL': '1', 05:38:06 INFO - 'TERM': 'linux', 05:38:06 INFO - 'TMOUT': '86400', 05:38:06 INFO - 'USER': 'cltbld', 05:38:06 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:38:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:06 INFO - '_': '/tools/buildbot/bin/python'} 05:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:38:06 INFO - Running setup.py (path:/tmp/pip-UyEv4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:38:06 INFO - Running setup.py (path:/tmp/pip-L2ENu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:38:06 INFO - Running setup.py (path:/tmp/pip-tysxDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:38:06 INFO - Running setup.py (path:/tmp/pip-VqIi0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:38:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:38:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:38:06 INFO - Running setup.py (path:/tmp/pip-_gscdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:38:07 INFO - Running setup.py (path:/tmp/pip-28921p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:38:07 INFO - Running setup.py (path:/tmp/pip-yp2OSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:38:07 INFO - Running setup.py (path:/tmp/pip-aX7u2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:38:07 INFO - Running setup.py (path:/tmp/pip-zHqfhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:38:07 INFO - Running setup.py (path:/tmp/pip-Ji7mW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:38:07 INFO - Running setup.py (path:/tmp/pip-R4BL2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:38:07 INFO - Running setup.py (path:/tmp/pip-tHASKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:38:07 INFO - Running setup.py (path:/tmp/pip-rzBCYK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:38:07 INFO - Running setup.py (path:/tmp/pip-02fo7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:07 INFO - Running setup.py (path:/tmp/pip-1bZatS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:38:07 INFO - Running setup.py (path:/tmp/pip-20VZnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:38:07 INFO - Running setup.py (path:/tmp/pip-Brc_XI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:38:07 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:38:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:38: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 05:38: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 05:38: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 05:38: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 05:38:09 INFO - Downloading blessings-1.6.tar.gz 05:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:38:09 INFO - Installing collected packages: blessings 05:38:09 INFO - Running setup.py install for blessings 05:38:09 INFO - Successfully installed blessings 05:38:09 INFO - Cleaning up... 05:38:09 INFO - Return code: 0 05:38:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:38:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:09 INFO - Reading from file tmpfile_stdout 05:38:09 INFO - Current package versions: 05:38:09 INFO - argparse == 1.2.1 05:38:09 INFO - blessings == 1.6 05:38:09 INFO - blobuploader == 1.2.4 05:38:09 INFO - docopt == 0.6.1 05:38:09 INFO - functools32 == 3.2.3-2 05:38:09 INFO - jsonschema == 2.5.1 05:38:09 INFO - manifestparser == 1.1 05:38:09 INFO - mozInstall == 1.13 05:38:09 INFO - mozcrash == 1.0 05:38:09 INFO - mozdebug == 0.1 05:38:09 INFO - mozdevice == 0.51 05:38:09 INFO - mozfile == 1.2 05:38:09 INFO - mozhttpd == 0.7 05:38:09 INFO - mozinfo == 0.10 05:38:09 INFO - mozleak == 0.1 05:38:09 INFO - mozlog == 3.5 05:38:09 INFO - moznetwork == 0.27 05:38:09 INFO - mozprocess == 0.25 05:38:09 INFO - mozprofile == 0.28 05:38:09 INFO - mozrunner == 6.13 05:38:09 INFO - mozscreenshot == 0.1 05:38:09 INFO - mozsystemmonitor == 0.3 05:38:09 INFO - moztest == 0.8 05:38:09 INFO - mozversion == 1.4 05:38:09 INFO - psutil == 3.1.1 05:38:09 INFO - requests == 1.2.3 05:38:09 INFO - wsgiref == 0.1.2 05:38:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:38: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')]} 05:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38: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')]} 05:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:09 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 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:38:09 INFO - Using env: {'DISPLAY': ':0', 05:38:09 INFO - 'HOME': '/home/cltbld', 05:38:09 INFO - 'LANG': 'en_US.UTF-8', 05:38:09 INFO - 'LANGUAGE': 'en_US:en', 05:38:09 INFO - 'LOGNAME': 'cltbld', 05:38:09 INFO - 'MAIL': '/var/mail/cltbld', 05:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:09 INFO - 'MOZ_NO_REMOTE': '1', 05:38:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:09 INFO - 'NO_EM_RESTART': '1', 05:38:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:09 INFO - 'PWD': '/builds/slave/test', 05:38:09 INFO - 'SHELL': '/bin/bash', 05:38:09 INFO - 'SHLVL': '1', 05:38:09 INFO - 'TERM': 'linux', 05:38:09 INFO - 'TMOUT': '86400', 05:38:09 INFO - 'USER': 'cltbld', 05:38:09 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:09 INFO - '_': '/tools/buildbot/bin/python'} 05:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:38:09 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:38:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:38:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:38:10 INFO - Installing collected packages: simplejson 05:38:10 INFO - Running setup.py install for simplejson 05:38:10 INFO - building 'simplejson._speedups' extension 05:38:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 05:38:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 05:38:11 INFO - Successfully installed simplejson 05:38:11 INFO - Cleaning up... 05:38:11 INFO - Return code: 0 05:38:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:38:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:38:11 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 0xd271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xef4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1097d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:38:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:38:11 INFO - Using env: {'DISPLAY': ':0', 05:38:11 INFO - 'HOME': '/home/cltbld', 05:38:11 INFO - 'LANG': 'en_US.UTF-8', 05:38:11 INFO - 'LANGUAGE': 'en_US:en', 05:38:11 INFO - 'LOGNAME': 'cltbld', 05:38:11 INFO - 'MAIL': '/var/mail/cltbld', 05:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:11 INFO - 'MOZ_NO_REMOTE': '1', 05:38:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:11 INFO - 'NO_EM_RESTART': '1', 05:38:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:11 INFO - 'PWD': '/builds/slave/test', 05:38:11 INFO - 'SHELL': '/bin/bash', 05:38:11 INFO - 'SHLVL': '1', 05:38:11 INFO - 'TERM': 'linux', 05:38:11 INFO - 'TMOUT': '86400', 05:38:11 INFO - 'USER': 'cltbld', 05:38:11 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:11 INFO - '_': '/tools/buildbot/bin/python'} 05:38:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:38:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:38:12 INFO - Cleaning up... 05:38:12 INFO - Return code: 0 05:38:12 INFO - Running post-action listener: _resource_record_post_action 05:38:12 INFO - Running post-action listener: _start_resource_monitoring 05:38:12 INFO - Starting resource monitoring. 05:38:12 INFO - [mozharness: 2017-08-07 12:38:12.169561Z] Finished create-virtualenv step (success) 05:38:12 INFO - [mozharness: 2017-08-07 12:38:12.170034Z] Running install step. 05:38:12 INFO - Running pre-action listener: _resource_record_pre_action 05:38:12 INFO - Running main action method: install 05:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:38:12 INFO - Reading from file tmpfile_stdout 05:38:12 INFO - Detecting whether we're running mozinstall >=1.0... 05:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:38:12 INFO - Reading from file tmpfile_stdout 05:38:12 INFO - Output received: 05:38:12 INFO - Usage: mozinstall [options] installer 05:38:12 INFO - Options: 05:38:12 INFO - -h, --help show this help message and exit 05:38:12 INFO - -d DEST, --destination=DEST 05:38:12 INFO - Directory to install application into. [default: 05:38:12 INFO - "/builds/slave/test"] 05:38:12 INFO - --app=APP Application being installed. [default: firefox] 05:38:12 INFO - mkdir: /builds/slave/test/build/application 05:38:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:38:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:38:30 INFO - Reading from file tmpfile_stdout 05:38:30 INFO - Output received: 05:38:30 INFO - /builds/slave/test/build/application/firefox/firefox 05:38:30 INFO - Running post-action listener: _resource_record_post_action 05:38:30 INFO - [mozharness: 2017-08-07 12:38:30.443982Z] Finished install step (success) 05:38:30 INFO - [mozharness: 2017-08-07 12:38:30.444138Z] Running setup-mitmproxy step. 05:38:30 INFO - Running pre-action listener: _resource_record_pre_action 05:38:30 INFO - Running main action method: setup_mitmproxy 05:38:30 INFO - Skipping: mitmproxy is not required 05:38:30 INFO - Running post-action listener: _resource_record_post_action 05:38:30 INFO - [mozharness: 2017-08-07 12:38:30.444677Z] Finished setup-mitmproxy step (success) 05:38:30 INFO - [mozharness: 2017-08-07 12:38:30.444780Z] Running run-tests step. 05:38:30 INFO - Running pre-action listener: _resource_record_pre_action 05:38:30 INFO - Running pre-action listener: _set_gcov_prefix 05:38:30 INFO - Running main action method: run_tests 05:38:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:38:30 INFO - Python 2.7.3 05:38:30 INFO - Return code: 0 05:38:30 INFO - grabbing minidump binary from tooltool 05:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:38:30 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 0x1094eb0>, '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 0x1095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:38:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:38:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:38:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:38:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:38:30 INFO - Return code: 0 05:38:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:38:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:38:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:38:30 INFO - ENV: RUST_BACKTRACE is now 1 05:38:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:38:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:38:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:38:30 INFO - Using env: {'DISPLAY': ':0', 05:38:30 INFO - 'HOME': '/home/cltbld', 05:38:30 INFO - 'LANG': 'en_US.UTF-8', 05:38:30 INFO - 'LANGUAGE': 'en_US:en', 05:38:30 INFO - 'LOGNAME': 'cltbld', 05:38:30 INFO - 'MAIL': '/var/mail/cltbld', 05:38:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:38:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:38:30 INFO - 'MOZ_NO_REMOTE': '1', 05:38:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:38:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:38:30 INFO - 'NO_EM_RESTART': '1', 05:38:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:38:30 INFO - 'PWD': '/builds/slave/test', 05:38:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:38:30 INFO - 'RUST_BACKTRACE': '1', 05:38:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:38:30 INFO - 'SHELL': '/bin/bash', 05:38:30 INFO - 'SHLVL': '1', 05:38:30 INFO - 'TERM': 'linux', 05:38:30 INFO - 'TMOUT': '86400', 05:38:30 INFO - 'USER': 'cltbld', 05:38:30 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135', 05:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:38:30 INFO - '_': '/tools/buildbot/bin/python'} 05:38:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:38:30 INFO - mozversion application_buildid: 20170807105655 05:38:30 INFO - mozversion application_changeset: 8437bfefc8417d057015e8b4d77b6a5c67360863 05:38:30 INFO - mozversion application_display_name: Nightly 05:38:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:38:30 INFO - mozversion application_name: Firefox 05:38:30 INFO - mozversion application_remotingname: firefox 05:38:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:38:30 INFO - mozversion application_vendor: Mozilla 05:38:30 INFO - mozversion application_version: 57.0a1 05:38:30 INFO - mozversion platform_buildid: 20170807105655 05:38:30 INFO - mozversion platform_changeset: 8437bfefc8417d057015e8b4d77b6a5c67360863 05:38:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:38:30 INFO - mozversion platform_version: 57.0a1 05:38:30 INFO - using testdate: 1502109510 05:38:30 INFO - actual date: 1502109510 05:38:30 INFO - starting webserver on 'localhost:56809' 05:38:30 INFO - SUITE-START | Running 2 tests 05:38:30 INFO - TEST-START | dromaeo_css 05:38:30 INFO - Initialising browser for dromaeo_css test... 05:38:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56809/getInfo.html -profile /tmp/tmpaoaJc5/profile 05:38:30 INFO - TEST-INFO | started process 15562 (/builds/slave/test/build/application/firefox/firefox http://localhost:56809/getInfo.html) 05:38:36 INFO - TEST-INFO | 15562: exit 0 05:38:36 INFO - Browser initialized. 05:38:36 INFO - Running cycle 1/1 for dromaeo_css test... 05:38:36 INFO - TEST-INFO | started process 15770 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaoaJc5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:38:37 INFO - PID 15770 | 05:38:37 INFO - PID 15770 | (/builds/slave/test/build/application/firefox/firefox:15818): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:38:37 INFO - PID 15770 | 05:38:47 INFO - PID 15770 | RSS: Main: 171458560 05:38:47 INFO - PID 15770 | 05:41:47 INFO - PID 15770 | Cycle 1(1): loaded http://localhost:56809/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56809/tests/dromaeo/cssquery-ext.html) 05:41:48 INFO - PID 15770 | RSS: Main: 172720128 05:41:48 INFO - PID 15770 | 05:44:49 INFO - PID 15770 | Cycle 1(1): loaded http://localhost:56809/tests/dromaeo/cssquery-ext.html (next: http://localhost:56809/tests/dromaeo/cssquery-jquery.html) 05:44:50 INFO - PID 15770 | RSS: Main: 170434560 05:44:50 INFO - PID 15770 | 05:47:52 INFO - PID 15770 | Cycle 1(1): loaded http://localhost:56809/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56809/tests/dromaeo/cssquery-mootools.html) 05:47:52 INFO - PID 15770 | RSS: Main: 172232704 05:47:52 INFO - PID 15770 | 05:50:54 INFO - PID 15770 | Cycle 1(1): loaded http://localhost:56809/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56809/tests/dromaeo/cssquery-prototype.html) 05:50:55 INFO - PID 15770 | RSS: Main: 171274240 05:50:55 INFO - PID 15770 | 05:53:58 INFO - PID 15770 | Cycle 1(1): loaded http://localhost:56809/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56809/tests/dromaeo/cssquery-yui.html) 05:53:59 INFO - PID 15770 | RSS: Main: 174354432 05:53:59 INFO - PID 15770 | 05:57:01 INFO - PID 15770 | Cycle 1(1): loaded http://localhost:56809/tests/dromaeo/cssquery-yui.html (next: http://localhost:56809/tests/dromaeo/cssquery-dojo.html) 05:57:01 INFO - PID 15770 | RSS: Main: 175439872 05:57:01 INFO - PID 15770 | 05:57:01 INFO - PID 15770 | __start_tp_report 05:57:01 INFO - PID 15770 | _x_x_mozilla_page_load 05:57:01 INFO - PID 15770 | _x_x_mozilla_page_load_details 05:57:01 INFO - PID 15770 | |i|pagename|runs| 05:57:01 INFO - PID 15770 | |0;dojo.html;3147.0716138739085;3181.300113974925;3184.6974537418946;3194.456942319808;3208.7272581830543;6163.876722465548;6254.781082662102;6283.685815709219;6318;6359.71381287842;6135.386461353863;6252.781152659658;6272.749090036405;6277.152584401116;6278.968605156968;5696.25948626679;5702.059160238556;5728.11214261788;5742.54059675225;5758.107493338539;5653.547716182696;5758.913616295759;5765.682887441189;5789.131630255474;5797.275340582427;5671.6597004179885;5765.971170144144;5770.769169233246;5781.13283007548;5815.651060936358;5521.917171242435;5647.463490968352;5737.340205876336;5762.078067509199;5784.276965379348;5626.549876009909;5628.409017053192;5629.3526244482;5684.062129748587;5705.058665320217;6104.755809767604;6122.632642041447;6144.293406258293;6185.381461853824;6209.565330426872;6510.609363438161;6607.1080404145305;6609.702563384659;6681.365270299314;6734.225564060147;6711.832204194934;6774.5257831951785;6794.45644348451;6795.966020169917;6816.659167041628;6196.566240363177;6204.627722336674;6271.874562508724;6286.371362863722;6291.622502649856;6641.800745977628;6834.931650683529;6911.03245545624;6925.168979722364;6932.757353492603;6493.577917435352;6514.54398192122;6561.868762624721;6594.043863639841;6600;6643.169603799525;6653.1018312527185;6706.295838936841;6736.494762892803;6773.6613169341335;6588.341165883373;6599.60402375859;6601.570897891717;6700.4639628830555;6713.530052896251;6501.2523559791225;6571;6618;6679.131712877295;6698.397144256943;5478.91781623276;5500.614956952975;5503.944960550343;5505.449455054462;5509.421510741315;4596.655250856199;4649.930251046237;4685.461171965268;4722;4727.338172655831;5776.162456443876;5776.566757493205;5786.1610066540115;5798.478136967693;5810.331811841692;6638.070670106189;6653.334666533405;6656.201255849329;6661.300563440769;6662.900056499059;5752.482276595127;5754.827355179351;5759.136129580513;5760.625559338713;5767.163761254595;5739.13912913058;5849.736761845727;5861.17943487912;5868.941310586925;5875.9118613220835;6501.804945851632;6503.934960650333;6529.575577587441;6532.640704761188;6541.0515475255825;6669.166354205724;6705.564138330993;6753.15585551806;6758.256591774999;6763.458923286125;6418.935810641834;6432.163818703539;6444.613323200623;6528.836779080561;6534.346565343618;6435.1312572803945;6449.838754031187;6542.771003014872;6547.639879806657;6560.40956313934;6656.134702488645;6664.866702666017;6707.66461676924;6717.899231511629;6720.798376048726;6381.266154392304;6398.712057957497;6484.935150648433;6493.610383377012;6514.315996820265;6357.8728425432155;6407.711652975534;6445.581037232565;6454.612723236621;6476.579022363531;6278.183836101278;6374.4900407966325;6375.4262116409745;6383.521235907233;6413.615183089029;6484.902726459201;6506.772262970773;6509;6585.6377899216;6623.470122390294;6254.061890716429;6280.811575652738;6310.936890631035;6389.9361006389345;6437.227532696106 05:57:01 INFO - PID 15770 | |1;ext.html;831.4055450352996;846.3990566697645;872.2629378175442;900.6487469886744;903.0923921458937;42267.154656906845;42908;42951;43043.56956430431;43189;38708.225835483274;39066.4140037899;39081.41377879334;39097.41353879694;39166.21667566647;7023.68393422296;7158.284171582852;7161.749338773144;7163.964180179092;7189;7050.224475307712;7132.5007249492555;7150;7151.427885769126;7153.535020223695;7098.219195888448;7103.502754807166;7128.037714908499;7142.78571642849;7144.821379465529;7029.543079699854;7053.224145344034;7090.85818283634;7105.8223544411285;7114.146302443688;6488.708008139598;6527.8694426111215;6533.509986751013;6539.64031978241;6542.182227221598;34727.13182170466;34853.65146348555;34866.47700284498;34978.475322870174;35027.824860875786;3360.865565377382;3389.474631432132;3390.440577304742;3392.4911263310455;3401.5748031496064;27779.305517362027;28000.299992500146;28043.158705238875;28175.154745357468;28187.295317617223;4424.7123936944;4442.866713998553;4496.392986946753;4511.1654343946475;4518.186726389248;180708.9999999987;181482;182151;182548;182822.0858895697;21941.670874936888;22000.669989950162;22004.77995220027;22016;22043.779562204494;22431.663525047137;22480.775192248195;22579;22648.433789155402;22657.88671056634;12853.742925141632;13136.802947955788;13157.88158006561;13244.404001819748;13280.203187808762;12906.935465322613;12996.545120920911;13027.934860325639;13035.674108147183;13144.211347319191;5693.9430605693415;5709.314882214161;5765;5800.1299805029585;5822.155787410887;2424.8302618816515;2430.708315002211;2453.2640207937548;2454.6686197363483;2461.101697880261;979.2851218610455;1003.9196864250843;1012.4330374990029;1016.3292227130058;1023.1150055202345;28386.71613283882;29440;29554.704452955626;29776.851115744717;29884.252893677396;1087.0922779479065;1087.1194332590626;1112.4660163121746;1115.9051480624237;1116.3302018788825;12372.81440778371;12446.937765311115;12452.937735311447;12496.25022498653;12533.749325013448;224754;227813;227850;228015;228347;47295.763521182176;48565;55358.72320638371;56132.719336403876;57115.42884571101;1489.6946126044136;1500.047469856627;1502.9098254104788;1503.3760989189582;1512.9319180636899;26599.86700066487;26648.20055398341;26717;26934.32664183411;27107.322316942234;37004.25991480101;37026.44460333151;37045.62954370422;37077.81461092785;37106.25787484289;185596;185705;186263;186545;186554;30550.847245763627;32031.199220019687;32091.67908320887;32217.51673724849;32359.838200808845;41516.79241603894;41598.37602436027;42000.57999419967;42038.36942445927;42046.78976605098;30780.84609576938;31159.532607011366;31309.686903130678;31347.84326078355;31460.685393145777;31091.533626995162;31154.688453115177;31315.84342078275;31474.842625786725;31811.840940795148;34495.65504345025;34617.82691086528;34636.307273853876;34860.477092844136;35004.64995350014;30724.692753072184;31161.37677246488;32818.83590582032;34229.65770342364;34499.31001380009;24373.75626243715;24609.753902461463;24848.254552363458;24909.62635560432;25271.49457010886 05:57:01 INFO - PID 15770 | |2;jquery.html;578.3002566894058;582.2750675409117;583.6439771739241;584.5849446892709;604.6130476495044;22591.435214119654;22771.20300789473;23642.408939776513;23658.526829463444;23682;26757.063502777357;27257.591136132974;27277.45445091097;27323.180304590893;27379.31551711208;6689.69896354664;6851.143607049119;6857.485688573352;6861.759838405656;6879.036934829103;6532.477401807868;6679.165104361955;6727.0918426012595;6803.047573339712;6815.863682726343;6649.634270115142;6653.168353955755;6654.234763002269;6658.167729033871;6672.132622759059;6641.302663220365;6642.634655093968;6662.800115996528;6679.198496180466;6679.93320066798;6177.62934223948;6186.412290832363;6188.47397971173;6201.937980620181;6214.782482613132;29149.708502914913;29861.850690746625;29875.103746887627;31420.528692069638;32788;1913.598144389666;1947.289239427613;1954.5797653504565;1955.4133759871982;1958;21387.572248555098;21580.89209553942;21672.783272167235;21681.2411565596;21702.457438564004;2404.651325557802;2480.2063339731294;2552.2088152672545;2557.8848951797213;2563.5898256279092;139828.30085849608;144364;145056;145260;145646.27176864154;21089.047533345343;21114;22097.121744651562;22758.544829103324;23274;44688.3296750549;44782.77608611936;44796.55203447989;44801.55198448039;44858.55141448544;11594.304341739522;11761.470733817;11778.175527712978;11816.763664726657;11976.76046479083;9903.306768526278;9969.451680157661;9978.850317245247;9980.301378903552;10032.147267482345;8988.370814042953;9269.90730092704;11721.655668866091;11816.46825892837;12489.250644961332;2697.9190624281305;2710.349516116118;2741.383188782508;2782.916512504628;2802.355458244615;904.8506996345661;933.9019402962726;972.5477652891298;990.9554070066864;991.7768502087117;19744.70382944257;20326.898365508077;20343.69484457734;20451.386458406272;20579.176832926816;1028.6605420211313;1041.099448976645;1052.8052310322614;1054.599252284127;1062.5909025025421;10869.217544280491;11397;13406.396712147976;13936.233507157203;14240.359183836545;486913;494158;518318;518612;534584;111990.44004779925;113109;114001.42999285116;114311;117750.41124794321;1287.1697754948007;1295.41706232194;1314.3953781260727;1319.8152258683792;1326.111505291457;21475.24836630773;21581;21913.561728765653;21914;21990.780092199515;58511;59386;59846.401535984085;60102;60493.69753151206;76151;76856.61571692106;76860;76932.23067769251;77171;14830.77753833715;15161.696766064837;15184;15224.695506090038;15257.923710381821;23585.174518892443;23707.52584948257;23793.52412951766;23818;24018.63972040386;18670.346537871654;18855.340063097727;19165.808341916403;19188.520286992938;19213.519662008002;17010.48968530946;17186.484405467854;17464.12679366052;17541.12294385301;17685.734713979044;18896.338628147947;18974;19227.51931201731;19239.807601923803;19372;14871.776923346375;15049.54851354461;15111.31999060023;15112.773308400163;15137.848621513644;18612.162452689932;18776.74679038524;18836.34072807445;18881.527961801065;18977.620447591245 05:57:01 INFO - PID 15770 | |3;mootools.html;1289.9935500322497;1369.1511263016926;1371.807946887436;1381.5233744358206;1384.3562743324353;13667.111637743552;14215;14231.217283049427;14260.71478570428;14277.643058923531;13846.515371961983;14703.632409189775;14723.26383680817;14732.558023259293;14755.114693118421;2689.542777727786;2753.821001634897;2754.2150487111176;2754.820936639122;2758.903438379657;2592.053900326386;2737.5209338365808;2740.945181096377;2752;2754.2701184186208;2696.514627367073;2728.140635699758;2743.27303264635;2757.7793776497933;2761.8066735328534;2674.358154043035;2676.0633778177676;2702.4459985702883;2718.306831757899;2751.834889906612;2553.19574334086;2566.1275166443497;2587.2884956636885;2588.314096764359;2588.8058395620405;13671.111377760513;14001.369938352696;14005.229712365815;14007.789883151761;14013.439462421491;7533.322001019879;7772.727954521564;7791.571463569501;7799.688012479494;7831.960840195819;5071.290019397287;5192.1432963560965;5281.419043905167;5287.788488460457;5305.734713264322;1759.463363674079;1815.5007372972407;1824.7080467125197;1860.3209828412666;1869.3550724999852;21333.89333053356;23176.420589485228;23559.29322120322;23582.764172358227;23656.40858978522;6474.546781725234;6675.3324667533825;6992.93007069933;7205.963970180115;7220.494565380373;18769.90615046916;18782.436526904214;18784.436466905743;18801.623967520845;18810;7764.495307804975;7962.761117166494;7963.323117535075;7974.202579742096;8295;7330.083739532559;7351.264873512606;7379.003834482383;7390.260973902674;7397.297256760599;5465.836024919259;5569.10894256917;5580.581456390787;5641.75881306112;5760.971195143998;1700.532353602769;1718.6390857919728;1726.2749645149067;1741.7822772153481;1757.7627020352345;895;920.1120918313859;930.4510338900081;934.9345545811727;943.4905151218403;7506.737264195727;7535.1334596521;7544.132424771112;7544.509606875646;7565.243475652501;696.6412297666706;726.4852781949588;732.2787054751066;738.265425901221;743.2864450127938;4840.419149702058;5659.490645841894;5792.88414231713;5839;5992.101184822312;19551;19645.31241406544;19977.900110499646;19993.900335481398;20003.899980500006;18554.165062572218;19601.313954011828;19634.803651963583;19681.90159049224;19682.60634787325;10252.58989640406;10487.947560262302;10522.842157367646;10551.68344949668;10579.576816927236;8546.487210767375;8585.742427727178;8616.78458038554;8619.956900215459;8690.869636955576;16591.170441477636;16976.23606937666;17021.659566808346;17208.13959302005;17299.13504324804;16997.49007529776;17068.23192956295;17284.740728888824;17397.30410783605;17649.470515884543;11471.827922581;11678;11697.649070527897;11700.35648039366;11712.121590880548;12835.87164128384;13008.544700935421;13155.473781048651;13176.47294108225;13249.205047697169;10675.199360048182;10794.514246858753;10882.347059176474;11036.503357348778;11208.27146235492;10590.099841513558;10605.363678179334;10614.893851061699;10764.838527422251;10842.728931776768;9642.325037247325;9752;9754.365966212174;9759.219262458844;9759.658411955548;7119.57934302319;7177.210506844139;7245.985562021427;7273.127224733066;7301.306375894282;7121.928780712126;7148.64256787169;7179.138503379628;7194.928050719426;7340.2292759261645 05:57:01 INFO - PID 15770 | |4;prototype.html;488.10676462074406;496.43902390298956;497.4975274972277;507.40886866800156;510.2346480279578;6175.3207147213825;6186.4432201101945;6228.22147231596;6233.7818176363835;6237.313895471494;6038.365971572976;6080.817575472732;6103.755849766004;6123.173371594833;6127.142200091991;5008.649394542384;5022.296878437003;5055.140626093573;5056.002878862847;5071.7210553419545;4774.856754297377;4884.487128851472;4891.192953162724;4953.876153096184;4995.975020124894;4827.203511420611;4846.466888642247;4854.975725121387;4900.736359367355;4941.283513890484;4870.634702397334;4886.63350248731;4920.778564964549;4946.576177672914;4971.950280497185;4396.78016099197;4414.359917811931;4436.578525040116;4469.240229161051;4472.373867658531;5999.640021598718;6001.759929602855;6043.1841701370195;6103.786367477117;6152.723127459275;5596.608237423382;5619.887602247933;5661;5683.914741278884;5715.685637289947;6605.240397354318;6613.93386066138;6621;6630.701618427182;6640.177924382118;4785.856424307276;4830.951690483085;4838.637102217348;4842.757862106881;4865.975670121662;6554.279029306773;6593.571417857824;6666.133642487346;6773.2070040776825;6861.55399899005;4902.779374928136;4910.508949105132;4917.336159618477;4924.3352147460355;4936.456989731127;5004.549590536869;5014.874628134253;5025.84922452327;5041.2438134280155;5084.949150508522;5592.944070559242;5625.6905870176715;5650.237217975603;5689.288838895138;5696.829095127153;6509.511786616023;6527.3472652734345;6548.410643042053;6585.703643335965;6610.173728283965;1754.4210410564483;1755.2803350626436;1772.5834428909195;1773.1045821860137;1774.9201285942163;1121.685927940178;1192.3740036480917;1194.6057800925673;1206.4088596587785;1207.3299318878107;5695.800646977336;5712.7714891403875;5740.0815869460685;5813;5820.854478638068;6672.666366681647;6682.389151485557;6729.25978142404;6744.527883048139;6798.762043328558;5719.655880867993;5778.16216648592;5797.333306669787;5797.797077102366;5846.532277417797;3551.786892786441;3589.730770192246;3594.281143771256;3617.8371973261264;3662.1943172502006;6551.4103730664465;6555.3772391622715;6560.671966401661;6562.343765623399;6586.209654841354;6843.270417622408;6856.931430685729;6949.89575156373;6975.82560435983;6994.230634630187;6443.5551958594915;6449.709763060768;6512.251091124582;6519.282878883224;6527.608343499311;6677.532572719863;6715;6734.696938637675;6767.086443330088;6783.796486105425;6634.535582509371;6637.601743895381;6676.365745254193;6689.431398331197;6729.192496900403;5787.479126878601;6557.311482294291;6557.508186885907;6562.5406221564035;6564.540482166202;4946.950530494649;4954.653174277766;4964.826231081895;4965.925511117264;4985.351904252424;6503.6422996733745;6638.867222655424;6649.966750166218;6753.86492270142;6769.93230067693;6464.741410343721;6477.578957367756;6505.869882602226;6511.34886511345;6584.604923704593;6549.508786840911;6552.508561857784;6577.50668699859;6579.243387010555;6622.271550129578;6340.49276057905;6387.688310390561;6409.679516024274;6463.547551671526;6526.4452521536205;6590.835229119119;6599.439047681001;6599.7360105595235;6600.399600399601;6608.306127856698;6300.440251258025;6349.3650634937985;6382.712777924911;6382.776602818879;6400.167978162809 05:57:01 INFO - PID 15770 | |5;yui.html;844.5355054719898;856.4261944497187;858.7337925243176;891.835010523053;907.3240435875272;18284.817151828476;18923.432297031093;19527.609447811035;19563.315283965065;19617.31339403121;20921.16315347388;21470.141194352207;21517.24689635863;21751.564968700615;21789.019494122764;1020.1736593359384;1059.2426415113232;1062.6387028410338;1063.122923588043;1063.7181146996052;978.0122076702511;1064.2071656615833;1065.137238836541;1065.4725910674188;1072.244067932106;1113.5156207049918;1114.2033446086048;1114.6210288501907;1114.6711720042606;1118.0217309853879;1098.527633117759;1103.47584897174;1105.2263415609066;1107.9058280046206;1108.2187058124043;816.1308206759841;818.705266104202;819.1644522586928;819.5533434278333;819.942604017719;19662.508437289183;20370.796292037037;20461.18155273787;20569.280075197446;20658.586828263502;21753.891230543904;21792.673109903204;22181.223657172082;22337.55324893493;22665.77334226653;16937.66124677512;17325.306987720476;17349.306027758874;17389.82610173895;17601.207945642476;3587.2287458196615;3592.263585964871;3596.7841929484052;3599.6220396858216;3600.315939971423;129086;132328.33835830857;132871;133803.33098334447;134015;17141.400050998156;17194.914025429793;17605.119744012747;17734.11329433523;17749.467515974542;31055.378892422024;32027.35945281081;32328.67671323304;32430.675693243236;32455.837720811243;7902.288794008567;7973.601319934096;7980.920190798018;8234.423590348619;8333.208345207195;3829.4064420014943;4385.51759306476;4391.912161756811;4406.228909940747;4414.448193975753;5345.358556973188;5446.645968012067;5517.61376703635;5519.69641669712;5526.336839579276;1009.9646512372032;1021.9233557483218;1028.6296933104077;1029.5881647341123;1031.7626945802508;668.8695704337705;691.7094820175538;694.4270976444453;694.6700317349218;706.3219309462974;11325.603603873826;11597.246178998337;12165.330906800034;12204.572839950737;12865.356732163355;660.4122331125302;668.6656671664168;674.4402146218627;689.2521614048813;696.1089805049545;5618.336780472198;5659.5755318351285;5728.112142617901;5840.737166827503;5856.180134781134;95591;98775;101632.49183754034;102360.48819755853;103288;88926.55536722275;91473.54263228775;92473.5376323114;93487;93506;76402.23597763952;77225;77729;77998.22001779909;79507.60246198732;12572.1199516033;13162.934185329012;13183.209007459584;13427.731445370842;13463.990200734788;34845;36010.27979440449;36744.63255367412;37006.6299337014;37570.43644345392;45152.54847451615;45386.09227815491;45386.77306613446;45825.312620311386;46428.767856160506;18163.636727265115;18753.156107975447;18781.6243675123;18804.247830086642;19003.714944276122;21115.57768844584;21131.471713206676;21354.14583416646;21620.243291484723;22828.771712282665;19451.61096778028;19605.11776970011;19981.100850462055;20025.899870500554;20087.698684520037;20040.799592003892;20614.896925515277;20623.79376206219;20670.689939651216;20719.378418647466;18474.353397631017;19260.711089333392;19497;19573.314933977243;19893.60212795765;12971.35143242853;13849.723005539632;14116.29418529049;14135.64660883486;14558.708825823212;13554.93222533881;14423.42306307736;14498.782518262024;14550.708985820012;14871.776923345942 05:57:01 INFO - PID 15770 | __end_tp_report 05:57:01 INFO - PID 15770 | __start_cc_report 05:57:01 INFO - PID 15770 | _x_x_mozilla_cycle_collect,360 05:57:01 INFO - PID 15770 | __end_cc_report 05:57:01 INFO - PID 15770 | __startTimestamp1502110621506__endTimestamp 05:57:01 INFO - PID 15770 | 05:57:01 INFO - PID 15770 | ------- Summary: start ------- 05:57:01 INFO - PID 15770 | Number of tests: 6 05:57:01 INFO - PID 15770 | 05:57:01 INFO - PID 15770 | [#0] dojo.html Cycles:165 Average:6143.13 Median:6381.27 stddev:710.04 (11.1%) stddev-sans-first:672.19 05:57:01 INFO - PID 15770 | Values: 3147.1 3181.3 3184.7 3194.5 3208.7 6163.9 6254.8 6283.7 6318.0 6359.7 6135.4 6252.8 6272.7 6277.2 6279.0 5696.3 5702.1 5728.1 5742.5 5758.1 5653.5 5758.9 5765.7 5789.1 5797.3 5671.7 5766.0 5770.8 5781.1 5815.7 5521.9 5647.5 5737.3 5762.1 5784.3 5626.5 5628.4 5629.4 5684.1 5705.1 6104.8 6122.6 6144.3 6185.4 6209.6 6510.6 6607.1 6609.7 6681.4 6734.2 6711.8 6774.5 6794.5 6796.0 6816.7 6196.6 6204.6 6271.9 6286.4 6291.6 6641.8 6834.9 6911.0 6925.2 6932.8 6493.6 6514.5 6561.9 6594.0 6600.0 6643.2 6653.1 6706.3 6736.5 6773.7 6588.3 6599.6 6601.6 6700.5 6713.5 6501.3 6571.0 6618.0 6679.1 6698.4 5478.9 5500.6 5503.9 5505.4 5509.4 4596.7 4649.9 4685.5 4722.0 4727.3 5776.2 5776.6 5786.2 5798.5 5810.3 6638.1 6653.3 6656.2 6661.3 6662.9 5752.5 5754.8 5759.1 5760.6 5767.2 5739.1 5849.7 5861.2 5868.9 5875.9 6501.8 6503.9 6529.6 6532.6 6541.1 6669.2 6705.6 6753.2 6758.3 6763.5 6418.9 6432.2 6444.6 6528.8 6534.3 6435.1 6449.8 6542.8 6547.6 6560.4 6656.1 6664.9 6707.7 6717.9 6720.8 6381.3 6398.7 6484.9 6493.6 6514.3 6357.9 6407.7 6445.6 6454.6 6476.6 6278.2 6374.5 6375.4 6383.5 6413.6 6484.9 6506.8 6509.0 6585.6 6623.5 6254.1 6280.8 6310.9 6389.9 6437.2 05:57:01 INFO - PID 15770 | 05:57:01 INFO - PID 15770 | [#1] ext.html Cycles:180 Average:34822.77 Median:31643.34 stddev:51878.03 (163.9%) stddev-sans-first:51960.78 05:57:01 INFO - PID 15770 | Values: 831.4 846.4 872.3 900.6 903.1 42267.2 42908.0 42951.0 43043.6 43189.0 38708.2 39066.4 39081.4 39097.4 39166.2 7023.7 7158.3 7161.7 7164.0 7189.0 7050.2 7132.5 7150.0 7151.4 7153.5 7098.2 7103.5 7128.0 7142.8 7144.8 7029.5 7053.2 7090.9 7105.8 7114.1 6488.7 6527.9 6533.5 6539.6 6542.2 34727.1 34853.7 34866.5 34978.5 35027.8 3360.9 3389.5 3390.4 3392.5 3401.6 27779.3 28000.3 28043.2 28175.2 28187.3 4424.7 4442.9 4496.4 4511.2 4518.2 180709.0 181482.0 182151.0 182548.0 182822.1 21941.7 22000.7 22004.8 22016.0 22043.8 22431.7 22480.8 22579.0 22648.4 22657.9 12853.7 13136.8 13157.9 13244.4 13280.2 12906.9 12996.5 13027.9 13035.7 13144.2 5693.9 5709.3 5765.0 5800.1 5822.2 2424.8 2430.7 2453.3 2454.7 2461.1 979.3 1003.9 1012.4 1016.3 1023.1 28386.7 29440.0 29554.7 29776.9 29884.3 1087.1 1087.1 1112.5 1115.9 1116.3 12372.8 12446.9 12452.9 12496.3 12533.7 224754.0 227813.0 227850.0 228015.0 228347.0 47295.8 48565.0 55358.7 56132.7 57115.4 1489.7 1500.0 1502.9 1503.4 1512.9 26599.9 26648.2 26717.0 26934.3 27107.3 37004.3 37026.4 37045.6 37077.8 37106.3 185596.0 185705.0 186263.0 186545.0 186554.0 30550.8 32031.2 32091.7 32217.5 32359.8 41516.8 41598.4 42000.6 42038.4 42046.8 30780.8 31159.5 31309.7 31347.8 31460.7 31091.5 31154.7 31315.8 31474.8 31811.8 34495.7 34617.8 34636.3 34860.5 35004.6 30724.7 31161.4 32818.8 34229.7 34499.3 24373.8 24609.8 24848.3 24909.6 25271.5 05:57:01 INFO - PID 15770 | 05:57:01 INFO - PID 15770 | [#2] jquery.html Cycles:180 Average:37075.62 Median:23429.59 stddev:85864.79 (366.5%) stddev-sans-first:86061.94 05:57:01 INFO - PID 15770 | Values: 578.3 582.3 583.6 584.6 604.6 22591.4 22771.2 23642.4 23658.5 23682.0 26757.1 27257.6 27277.5 27323.2 27379.3 6689.7 6851.1 6857.5 6861.8 6879.0 6532.5 6679.2 6727.1 6803.0 6815.9 6649.6 6653.2 6654.2 6658.2 6672.1 6641.3 6642.6 6662.8 6679.2 6679.9 6177.6 6186.4 6188.5 6201.9 6214.8 29149.7 29861.9 29875.1 31420.5 32788.0 1913.6 1947.3 1954.6 1955.4 1958.0 21387.6 21580.9 21672.8 21681.2 21702.5 2404.7 2480.2 2552.2 2557.9 2563.6 139828.3 144364.0 145056.0 145260.0 145646.3 21089.0 21114.0 22097.1 22758.5 23274.0 44688.3 44782.8 44796.6 44801.6 44858.6 11594.3 11761.5 11778.2 11816.8 11976.8 9903.3 9969.5 9978.9 9980.3 10032.1 8988.4 9269.9 11721.7 11816.5 12489.3 2697.9 2710.3 2741.4 2782.9 2802.4 904.9 933.9 972.5 991.0 991.8 19744.7 20326.9 20343.7 20451.4 20579.2 1028.7 1041.1 1052.8 1054.6 1062.6 10869.2 11397.0 13406.4 13936.2 14240.4 486913.0 494158.0 518318.0 518612.0 534584.0 111990.4 113109.0 114001.4 114311.0 117750.4 1287.2 1295.4 1314.4 1319.8 1326.1 21475.2 21581.0 21913.6 21914.0 21990.8 58511.0 59386.0 59846.4 60102.0 60493.7 76151.0 76856.6 76860.0 76932.2 77171.0 14830.8 15161.7 15184.0 15224.7 15257.9 23585.2 23707.5 23793.5 23818.0 24018.6 18670.3 18855.3 19165.8 19188.5 19213.5 17010.5 17186.5 17464.1 17541.1 17685.7 18896.3 18974.0 19227.5 19239.8 19372.0 14871.8 15049.5 15111.3 15112.8 15137.8 18612.2 18776.7 18836.3 18881.5 18977.6 05:57:01 INFO - PID 15770 | 05:57:01 INFO - PID 15770 | [#3] mootools.html Cycles:180 Average:8935.61 Median:2633.21 stddev:6070.58 (230.5%) stddev-sans-first:6060.42 05:57:01 INFO - PID 15770 | Values: 1290.0 1369.2 1371.8 1381.5 1384.4 13667.1 14215.0 14231.2 14260.7 14277.6 13846.5 14703.6 14723.3 14732.6 14755.1 2689.5 2753.8 2754.2 2754.8 2758.9 2592.1 2737.5 2740.9 2752.0 2754.3 2696.5 2728.1 2743.3 2757.8 2761.8 2674.4 2676.1 2702.4 2718.3 2751.8 2553.2 2566.1 2587.3 2588.3 2588.8 13671.1 14001.4 14005.2 14007.8 14013.4 7533.3 7772.7 7791.6 7799.7 7832.0 5071.3 5192.1 5281.4 5287.8 5305.7 1759.5 1815.5 1824.7 1860.3 1869.4 21333.9 23176.4 23559.3 23582.8 23656.4 6474.5 6675.3 6992.9 7206.0 7220.5 18769.9 18782.4 18784.4 18801.6 18810.0 7764.5 7962.8 7963.3 7974.2 8295.0 7330.1 7351.3 7379.0 7390.3 7397.3 5465.8 5569.1 5580.6 5641.8 5761.0 1700.5 1718.6 1726.3 1741.8 1757.8 895.0 920.1 930.5 934.9 943.5 7506.7 7535.1 7544.1 7544.5 7565.2 696.6 726.5 732.3 738.3 743.3 4840.4 5659.5 5792.9 5839.0 5992.1 19551.0 19645.3 19977.9 19993.9 20003.9 18554.2 19601.3 19634.8 19681.9 19682.6 10252.6 10487.9 10522.8 10551.7 10579.6 8546.5 8585.7 8616.8 8620.0 8690.9 16591.2 16976.2 17021.7 17208.1 17299.1 16997.5 17068.2 17284.7 17397.3 17649.5 11471.8 11678.0 11697.6 11700.4 11712.1 12835.9 13008.5 13155.5 13176.5 13249.2 10675.2 10794.5 10882.3 11036.5 11208.3 10590.1 10605.4 10614.9 10764.8 10842.7 9642.3 9752.0 9754.4 9759.2 9759.7 7119.6 7177.2 7246.0 7273.1 7301.3 7121.9 7148.6 7179.1 7194.9 7340.2 05:57:01 INFO - PID 15770 | 05:57:01 INFO - PID 15770 | [#4] prototype.html Cycles:180 Average:5492.16 Median:6092.29 stddev:1549.08 (25.4%) stddev-sans-first:1507.21 05:57:01 INFO - PID 15770 | Values: 488.1 496.4 497.5 507.4 510.2 6175.3 6186.4 6228.2 6233.8 6237.3 6038.4 6080.8 6103.8 6123.2 6127.1 5008.6 5022.3 5055.1 5056.0 5071.7 4774.9 4884.5 4891.2 4953.9 4996.0 4827.2 4846.5 4855.0 4900.7 4941.3 4870.6 4886.6 4920.8 4946.6 4972.0 4396.8 4414.4 4436.6 4469.2 4472.4 5999.6 6001.8 6043.2 6103.8 6152.7 5596.6 5619.9 5661.0 5683.9 5715.7 6605.2 6613.9 6621.0 6630.7 6640.2 4785.9 4831.0 4838.6 4842.8 4866.0 6554.3 6593.6 6666.1 6773.2 6861.6 4902.8 4910.5 4917.3 4924.3 4936.5 5004.5 5014.9 5025.8 5041.2 5084.9 5592.9 5625.7 5650.2 5689.3 5696.8 6509.5 6527.3 6548.4 6585.7 6610.2 1754.4 1755.3 1772.6 1773.1 1774.9 1121.7 1192.4 1194.6 1206.4 1207.3 5695.8 5712.8 5740.1 5813.0 5820.9 6672.7 6682.4 6729.3 6744.5 6798.8 5719.7 5778.2 5797.3 5797.8 5846.5 3551.8 3589.7 3594.3 3617.8 3662.2 6551.4 6555.4 6560.7 6562.3 6586.2 6843.3 6856.9 6949.9 6975.8 6994.2 6443.6 6449.7 6512.3 6519.3 6527.6 6677.5 6715.0 6734.7 6767.1 6783.8 6634.5 6637.6 6676.4 6689.4 6729.2 5787.5 6557.3 6557.5 6562.5 6564.5 4947.0 4954.7 4964.8 4965.9 4985.4 6503.6 6638.9 6650.0 6753.9 6769.9 6464.7 6477.6 6505.9 6511.3 6584.6 6549.5 6552.5 6577.5 6579.2 6622.3 6340.5 6387.7 6409.7 6463.5 6526.4 6590.8 6599.4 6599.7 6600.4 6608.3 6300.4 6349.4 6382.7 6382.8 6400.2 05:57:01 INFO - PID 15770 | 05:57:01 INFO - PID 15770 | [#5] yui.html Cycles:180 Average:22880.42 Median:20695.03 stddev:30338.47 (146.6%) stddev-sans-first:30378.45 05:57:01 INFO - PID 15770 | Values: 844.5 856.4 858.7 891.8 907.3 18284.8 18923.4 19527.6 19563.3 19617.3 20921.2 21470.1 21517.2 21751.6 21789.0 1020.2 1059.2 1062.6 1063.1 1063.7 978.0 1064.2 1065.1 1065.5 1072.2 1113.5 1114.2 1114.6 1114.7 1118.0 1098.5 1103.5 1105.2 1107.9 1108.2 816.1 818.7 819.2 819.6 819.9 19662.5 20370.8 20461.2 20569.3 20658.6 21753.9 21792.7 22181.2 22337.6 22665.8 16937.7 17325.3 17349.3 17389.8 17601.2 3587.2 3592.3 3596.8 3599.6 3600.3 129086.0 132328.3 132871.0 133803.3 134015.0 17141.4 17194.9 17605.1 17734.1 17749.5 31055.4 32027.4 32328.7 32430.7 32455.8 7902.3 7973.6 7980.9 8234.4 8333.2 3829.4 4385.5 4391.9 4406.2 4414.4 5345.4 5446.6 5517.6 5519.7 5526.3 1010.0 1021.9 1028.6 1029.6 1031.8 668.9 691.7 694.4 694.7 706.3 11325.6 11597.2 12165.3 12204.6 12865.4 660.4 668.7 674.4 689.3 696.1 5618.3 5659.6 5728.1 5840.7 5856.2 95591.0 98775.0 101632.5 102360.5 103288.0 88926.6 91473.5 92473.5 93487.0 93506.0 76402.2 77225.0 77729.0 77998.2 79507.6 12572.1 13162.9 13183.2 13427.7 13464.0 34845.0 36010.3 36744.6 37006.6 37570.4 45152.5 45386.1 45386.8 45825.3 46428.8 18163.6 18753.2 18781.6 18804.2 19003.7 21115.6 21131.5 21354.1 21620.2 22828.8 19451.6 19605.1 19981.1 20025.9 20087.7 20040.8 20614.9 20623.8 20670.7 20719.4 18474.4 19260.7 19497.0 19573.3 19893.6 12971.4 13849.7 14116.3 14135.6 14558.7 13554.9 14423.4 14498.8 14550.7 14871.8 05:57:01 INFO - PID 15770 | -------- Summary: end -------- 05:57:01 INFO - PID 15770 | 05:57:02 INFO - TEST-INFO | 15770: exit 0 05:57:02 INFO - TEST-OK | dromaeo_css | took 1111315ms 05:57:02 INFO - TEST-START | kraken 05:57:02 INFO - Initialising browser for kraken test... 05:57:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56809/getInfo.html -profile /tmp/tmpdfid4r/profile 05:57:02 INFO - TEST-INFO | started process 16130 (/builds/slave/test/build/application/firefox/firefox http://localhost:56809/getInfo.html) 05:57:07 INFO - TEST-INFO | 16130: exit 0 05:57:07 INFO - Browser initialized. 05:57:07 INFO - Running cycle 1/1 for kraken test... 05:57:07 INFO - TEST-INFO | started process 16336 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdfid4r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:57:08 INFO - PID 16336 | 05:57:08 INFO - PID 16336 | (/builds/slave/test/build/application/firefox/firefox:16385): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:57:08 INFO - PID 16336 | 05:57:18 INFO - PID 16336 | RSS: Main: 169336832 05:57:18 INFO - PID 16336 | 05:57:21 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56809/tests/kraken/driver.html?testName=audio-beat-detection) 05:57:22 INFO - PID 16336 | RSS: Main: 175095808 05:57:22 INFO - PID 16336 | 05:57:24 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56809/tests/kraken/driver.html?testName=audio-dft) 05:57:25 INFO - PID 16336 | RSS: Main: 175337472 05:57:25 INFO - PID 16336 | 05:57:28 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56809/tests/kraken/driver.html?testName=audio-fft) 05:57:29 INFO - PID 16336 | RSS: Main: 169943040 05:57:29 INFO - PID 16336 | 05:57:31 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56809/tests/kraken/driver.html?testName=audio-oscillator) 05:57:31 INFO - PID 16336 | RSS: Main: 170332160 05:57:31 INFO - PID 16336 | 05:57:33 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56809/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:57:34 INFO - PID 16336 | RSS: Main: 170745856 05:57:34 INFO - PID 16336 | 05:57:37 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56809/tests/kraken/driver.html?testName=imaging-darkroom) 05:57:38 INFO - PID 16336 | RSS: Main: 173604864 05:57:38 INFO - PID 16336 | 05:57:41 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56809/tests/kraken/driver.html?testName=imaging-desaturate) 05:57:42 INFO - PID 16336 | RSS: Main: 174092288 05:57:42 INFO - PID 16336 | 05:57:45 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56809/tests/kraken/driver.html?testName=json-parse-financial) 05:57:45 INFO - PID 16336 | RSS: Main: 174198784 05:57:45 INFO - PID 16336 | 05:57:47 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56809/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:57:48 INFO - PID 16336 | RSS: Main: 173756416 05:57:48 INFO - PID 16336 | 05:57:49 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56809/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:57:50 INFO - PID 16336 | RSS: Main: 173690880 05:57:50 INFO - PID 16336 | 05:57:52 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56809/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:57:53 INFO - PID 16336 | RSS: Main: 171347968 05:57:53 INFO - PID 16336 | 05:57:55 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:57:56 INFO - PID 16336 | RSS: Main: 171380736 05:57:56 INFO - PID 16336 | 05:57:59 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:58:00 INFO - PID 16336 | RSS: Main: 171761664 05:58:00 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | Cycle 1(1): loaded http://localhost:56809/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56809/tests/kraken/driver.html?testName=ai-astar) 05:58:02 INFO - PID 16336 | RSS: Main: 173309952 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | __start_tp_report 05:58:02 INFO - PID 16336 | _x_x_mozilla_page_load 05:58:02 INFO - PID 16336 | _x_x_mozilla_page_load_details 05:58:02 INFO - PID 16336 | |i|pagename|runs| 05:58:02 INFO - PID 16336 | |0;ai-astar;98;109;101;107;107;110;107;99;110;110 05:58:02 INFO - PID 16336 | |1;audio-beat-detection;147;128;156;167;123;153;149;119;177;119 05:58:02 INFO - PID 16336 | |2;audio-dft;164;163;164;162;162;164;161;168;166;161 05:58:02 INFO - PID 16336 | |3;audio-fft;76;76;74;180;76;76;74;74;151;75 05:58:02 INFO - PID 16336 | |4;audio-oscillator;79;79;128;79;79;79;123;79;80;129 05:58:02 INFO - PID 16336 | |5;imaging-gaussian-blur;114;115;115;115;114;114;114;115;113;113 05:58:02 INFO - PID 16336 | |6;imaging-darkroom;155;150;153;151;150;150;149;151;150;149 05:58:02 INFO - PID 16336 | |7;imaging-desaturate;94;94;94;95;95;94;94;94;98;95 05:58:02 INFO - PID 16336 | |8;json-parse-financial;55;57;53;55;58;54;54;54;53;54 05:58:02 INFO - PID 16336 | |9;json-stringify-tinderbox;54;54;55;54;56;54;55;54;54;55 05:58:02 INFO - PID 16336 | |10;stanford-crypto-aes;81;88;78;79;79;79;79;88;78;78 05:58:02 INFO - PID 16336 | |11;stanford-crypto-ccm;129;130;132;140;122;125;134;134;123;176 05:58:02 INFO - PID 16336 | |12;stanford-crypto-pbkdf2;183;174;180;181;189;173;176;177;176;177 05:58:02 INFO - PID 16336 | |13;stanford-crypto-sha256-iterative;77;67;66;69;65;65;65;66;64;65 05:58:02 INFO - PID 16336 | __end_tp_report 05:58:02 INFO - PID 16336 | __start_cc_report 05:58:02 INFO - PID 16336 | _x_x_mozilla_cycle_collect,931 05:58:02 INFO - PID 16336 | __end_cc_report 05:58:02 INFO - PID 16336 | __startTimestamp1502110682389__endTimestamp 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | ------- Summary: start ------- 05:58:02 INFO - PID 16336 | Number of tests: 14 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#0] ai-astar Cycles:10 Average:105.80 Median:110.00 stddev:4.69 (4.3%) stddev-sans-first:4.03 05:58:02 INFO - PID 16336 | Values: 98.0 109.0 101.0 107.0 107.0 110.0 107.0 99.0 110.0 110.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:151.00 stddev:20.61 (13.7%) stddev-sans-first:21.83 05:58:02 INFO - PID 16336 | Values: 147.0 128.0 156.0 167.0 123.0 153.0 149.0 119.0 177.0 119.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#2] audio-dft Cycles:10 Average:163.50 Median:164.00 stddev:2.22 (1.4%) stddev-sans-first:2.35 05:58:02 INFO - PID 16336 | Values: 164.0 163.0 164.0 162.0 162.0 164.0 161.0 168.0 166.0 161.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#3] audio-fft Cycles:10 Average:93.20 Median:75.50 stddev:38.72 (51.3%) stddev-sans-first:40.57 05:58:02 INFO - PID 16336 | Values: 76.0 76.0 74.0 180.0 76.0 76.0 74.0 74.0 151.0 75.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:79.00 stddev:23.01 (29.1%) stddev-sans-first:23.81 05:58:02 INFO - PID 16336 | Values: 79.0 79.0 128.0 79.0 79.0 79.0 123.0 79.0 80.0 129.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.50 stddev:0.79 (0.7%) stddev-sans-first:0.83 05:58:02 INFO - PID 16336 | Values: 114.0 115.0 115.0 115.0 114.0 114.0 114.0 115.0 113.0 113.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:150.50 stddev:1.87 (1.2%) stddev-sans-first:1.22 05:58:02 INFO - PID 16336 | Values: 155.0 150.0 153.0 151.0 150.0 150.0 149.0 151.0 150.0 149.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.50 stddev:1.25 (1.3%) stddev-sans-first:1.30 05:58:02 INFO - PID 16336 | Values: 94.0 94.0 94.0 95.0 95.0 94.0 94.0 94.0 98.0 95.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:54.50 stddev:1.64 (3.0%) stddev-sans-first:1.73 05:58:02 INFO - PID 16336 | Values: 55.0 57.0 53.0 55.0 58.0 54.0 54.0 54.0 53.0 54.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#9] json-stringify-tinderbox Cycles:10 Average:54.50 Median:54.50 stddev:0.71 (1.3%) stddev-sans-first:0.73 05:58:02 INFO - PID 16336 | Values: 54.0 54.0 55.0 54.0 56.0 54.0 55.0 54.0 54.0 55.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:79.00 stddev:3.95 (5.0%) stddev-sans-first:4.18 05:58:02 INFO - PID 16336 | Values: 81.0 88.0 78.0 79.0 79.0 79.0 79.0 88.0 78.0 78.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#11] stanford-crypto-ccm Cycles:10 Average:134.50 Median:133.00 stddev:15.59 (11.7%) stddev-sans-first:16.41 05:58:02 INFO - PID 16336 | Values: 129.0 130.0 132.0 140.0 122.0 125.0 134.0 134.0 123.0 176.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:178.50 stddev:4.79 (2.7%) stddev-sans-first:4.81 05:58:02 INFO - PID 16336 | Values: 183.0 174.0 180.0 181.0 189.0 173.0 176.0 177.0 176.0 177.0 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.00 stddev:3.81 (5.8%) stddev-sans-first:1.48 05:58:02 INFO - PID 16336 | Values: 77.0 67.0 66.0 69.0 65.0 65.0 65.0 66.0 64.0 65.0 05:58:02 INFO - PID 16336 | -------- Summary: end -------- 05:58:02 INFO - PID 16336 | 05:58:02 INFO - PID 16336 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:58:03 INFO - TEST-INFO | 16336: exit 0 05:58:03 INFO - TEST-OK | kraken | took 60993ms 05:58:03 INFO - SUITE-END | took 1172s 05:58:03 INFO - Completed test suite (00:19:32) 05:58:03 INFO - javascript benchmark 05:58:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8439.61992945989, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.0716138739085, 3181.300113974925, 3184.6974537418946, 3194.456942319808, 3208.7272581830543, 6163.876722465548, 6254.781082662102, 6283.685815709219, 6318.0, 6359.71381287842, 6135.386461353863, 6252.781152659658, 6272.749090036405, 6277.152584401116, 6278.968605156968, 5696.25948626679, 5702.059160238556, 5728.11214261788, 5742.54059675225, 5758.107493338539, 5653.547716182696, 5758.913616295759, 5765.682887441189, 5789.131630255474, 5797.275340582427, 5671.6597004179885, 5765.971170144144, 5770.769169233246, 5781.13283007548, 5815.651060936358, 5521.917171242435, 5647.463490968352, 5737.340205876336, 5762.078067509199, 5784.276965379348, 5626.549876009909, 5628.409017053192, 5629.3526244482, 5684.062129748587, 5705.058665320217, 6104.755809767604, 6122.632642041447, 6144.293406258293, 6185.381461853824, 6209.565330426872, 6510.609363438161, 6607.1080404145305, 6609.702563384659, 6681.365270299314, 6734.225564060147, 6711.832204194934, 6774.5257831951785, 6794.45644348451, 6795.966020169917, 6816.659167041628, 6196.566240363177, 6204.627722336674, 6271.874562508724, 6286.371362863722, 6291.622502649856, 6641.800745977628, 6834.931650683529, 6911.03245545624, 6925.168979722364, 6932.757353492603, 6493.577917435352, 6514.54398192122, 6561.868762624721, 6594.043863639841, 6600.0, 6643.169603799525, 6653.1018312527185, 6706.295838936841, 6736.494762892803, 6773.6613169341335, 6588.341165883373, 6599.60402375859, 6601.570897891717, 6700.4639628830555, 6713.530052896251, 6501.2523559791225, 6571.0, 6618.0, 6679.131712877295, 6698.397144256943, 5478.91781623276, 5500.614956952975, 5503.944960550343, 5505.449455054462, 5509.421510741315, 4596.655250856199, 4649.930251046237, 4685.461171965268, 4722.0, 4727.338172655831, 5776.162456443876, 5776.566757493205, 5786.1610066540115, 5798.478136967693, 5810.331811841692, 6638.070670106189, 6653.334666533405, 6656.201255849329, 6661.300563440769, 6662.900056499059, 5752.482276595127, 5754.827355179351, 5759.136129580513, 5760.625559338713, 5767.163761254595, 5739.13912913058, 5849.736761845727, 5861.17943487912, 5868.941310586925, 5875.9118613220835, 6501.804945851632, 6503.934960650333, 6529.575577587441, 6532.640704761188, 6541.0515475255825, 6669.166354205724, 6705.564138330993, 6753.15585551806, 6758.256591774999, 6763.458923286125, 6418.935810641834, 6432.163818703539, 6444.613323200623, 6528.836779080561, 6534.346565343618, 6435.1312572803945, 6449.838754031187, 6542.771003014872, 6547.639879806657, 6560.40956313934, 6656.134702488645, 6664.866702666017, 6707.66461676924, 6717.899231511629, 6720.798376048726, 6381.266154392304, 6398.712057957497, 6484.935150648433, 6493.610383377012, 6514.315996820265, 6357.8728425432155, 6407.711652975534, 6445.581037232565, 6454.612723236621, 6476.579022363531, 6278.183836101278, 6374.4900407966325, 6375.4262116409745, 6383.521235907233, 6413.615183089029, 6484.902726459201, 6506.772262970773, 6509.0, 6585.6377899216, 6623.470122390294, 6254.061890716429, 6280.811575652738, 6310.936890631035, 6389.9361006389345, 6437.227532696106], "value": 6090.946417357413, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.4055450352996, 846.3990566697645, 872.2629378175442, 900.6487469886744, 903.0923921458937, 42267.154656906845, 42908.0, 42951.0, 43043.56956430431, 43189.0, 38708.225835483274, 39066.4140037899, 39081.41377879334, 39097.41353879694, 39166.21667566647, 7023.68393422296, 7158.284171582852, 7161.749338773144, 7163.964180179092, 7189.0, 7050.224475307712, 7132.5007249492555, 7150.0, 7151.427885769126, 7153.535020223695, 7098.219195888448, 7103.502754807166, 7128.037714908499, 7142.78571642849, 7144.821379465529, 7029.543079699854, 7053.224145344034, 7090.85818283634, 7105.8223544411285, 7114.146302443688, 6488.708008139598, 6527.8694426111215, 6533.509986751013, 6539.64031978241, 6542.182227221598, 34727.13182170466, 34853.65146348555, 34866.47700284498, 34978.475322870174, 35027.824860875786, 3360.865565377382, 3389.474631432132, 3390.440577304742, 3392.4911263310455, 3401.5748031496064, 27779.305517362027, 28000.299992500146, 28043.158705238875, 28175.154745357468, 28187.295317617223, 4424.7123936944, 4442.866713998553, 4496.392986946753, 4511.1654343946475, 4518.186726389248, 180708.9999999987, 181482.0, 182151.0, 182548.0, 182822.0858895697, 21941.670874936888, 22000.669989950162, 22004.77995220027, 22016.0, 22043.779562204494, 22431.663525047137, 22480.775192248195, 22579.0, 22648.433789155402, 22657.88671056634, 12853.742925141632, 13136.802947955788, 13157.88158006561, 13244.404001819748, 13280.203187808762, 12906.935465322613, 12996.545120920911, 13027.934860325639, 13035.674108147183, 13144.211347319191, 5693.9430605693415, 5709.314882214161, 5765.0, 5800.1299805029585, 5822.155787410887, 2424.8302618816515, 2430.708315002211, 2453.2640207937548, 2454.6686197363483, 2461.101697880261, 979.2851218610455, 1003.9196864250843, 1012.4330374990029, 1016.3292227130058, 1023.1150055202345, 28386.71613283882, 29440.0, 29554.704452955626, 29776.851115744717, 29884.252893677396, 1087.0922779479065, 1087.1194332590626, 1112.4660163121746, 1115.9051480624237, 1116.3302018788825, 12372.81440778371, 12446.937765311115, 12452.937735311447, 12496.25022498653, 12533.749325013448, 224754.0, 227813.0, 227850.0, 228015.0, 228347.0, 47295.763521182176, 48565.0, 55358.72320638371, 56132.719336403876, 57115.42884571101, 1489.6946126044136, 1500.047469856627, 1502.9098254104788, 1503.3760989189582, 1512.9319180636899, 26599.86700066487, 26648.20055398341, 26717.0, 26934.32664183411, 27107.322316942234, 37004.25991480101, 37026.44460333151, 37045.62954370422, 37077.81461092785, 37106.25787484289, 185596.0, 185705.0, 186263.0, 186545.0, 186554.0, 30550.847245763627, 32031.199220019687, 32091.67908320887, 32217.51673724849, 32359.838200808845, 41516.79241603894, 41598.37602436027, 42000.57999419967, 42038.36942445927, 42046.78976605098, 30780.84609576938, 31159.532607011366, 31309.686903130678, 31347.84326078355, 31460.685393145777, 31091.533626995162, 31154.688453115177, 31315.84342078275, 31474.842625786725, 31811.840940795148, 34495.65504345025, 34617.82691086528, 34636.307273853876, 34860.477092844136, 35004.64995350014, 30724.692753072184, 31161.37677246488, 32818.83590582032, 34229.65770342364, 34499.31001380009, 24373.75626243715, 24609.753902461463, 24848.254552363458, 24909.62635560432, 25271.49457010886], "value": 15248.580448083932, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.3002566894058, 582.2750675409117, 583.6439771739241, 584.5849446892709, 604.6130476495044, 22591.435214119654, 22771.20300789473, 23642.408939776513, 23658.526829463444, 23682.0, 26757.063502777357, 27257.591136132974, 27277.45445091097, 27323.180304590893, 27379.31551711208, 6689.69896354664, 6851.143607049119, 6857.485688573352, 6861.759838405656, 6879.036934829103, 6532.477401807868, 6679.165104361955, 6727.0918426012595, 6803.047573339712, 6815.863682726343, 6649.634270115142, 6653.168353955755, 6654.234763002269, 6658.167729033871, 6672.132622759059, 6641.302663220365, 6642.634655093968, 6662.800115996528, 6679.198496180466, 6679.93320066798, 6177.62934223948, 6186.412290832363, 6188.47397971173, 6201.937980620181, 6214.782482613132, 29149.708502914913, 29861.850690746625, 29875.103746887627, 31420.528692069638, 32788.0, 1913.598144389666, 1947.289239427613, 1954.5797653504565, 1955.4133759871982, 1958.0, 21387.572248555098, 21580.89209553942, 21672.783272167235, 21681.2411565596, 21702.457438564004, 2404.651325557802, 2480.2063339731294, 2552.2088152672545, 2557.8848951797213, 2563.5898256279092, 139828.30085849608, 144364.0, 145056.0, 145260.0, 145646.27176864154, 21089.047533345343, 21114.0, 22097.121744651562, 22758.544829103324, 23274.0, 44688.3296750549, 44782.77608611936, 44796.55203447989, 44801.55198448039, 44858.55141448544, 11594.304341739522, 11761.470733817, 11778.175527712978, 11816.763664726657, 11976.76046479083, 9903.306768526278, 9969.451680157661, 9978.850317245247, 9980.301378903552, 10032.147267482345, 8988.370814042953, 9269.90730092704, 11721.655668866091, 11816.46825892837, 12489.250644961332, 2697.9190624281305, 2710.349516116118, 2741.383188782508, 2782.916512504628, 2802.355458244615, 904.8506996345661, 933.9019402962726, 972.5477652891298, 990.9554070066864, 991.7768502087117, 19744.70382944257, 20326.898365508077, 20343.69484457734, 20451.386458406272, 20579.176832926816, 1028.6605420211313, 1041.099448976645, 1052.8052310322614, 1054.599252284127, 1062.5909025025421, 10869.217544280491, 11397.0, 13406.396712147976, 13936.233507157203, 14240.359183836545, 486913.0, 494158.0, 518318.0, 518612.0, 534584.0, 111990.44004779925, 113109.0, 114001.42999285116, 114311.0, 117750.41124794321, 1287.1697754948007, 1295.41706232194, 1314.3953781260727, 1319.8152258683792, 1326.111505291457, 21475.24836630773, 21581.0, 21913.561728765653, 21914.0, 21990.780092199515, 58511.0, 59386.0, 59846.401535984085, 60102.0, 60493.69753151206, 76151.0, 76856.61571692106, 76860.0, 76932.23067769251, 77171.0, 14830.77753833715, 15161.696766064837, 15184.0, 15224.695506090038, 15257.923710381821, 23585.174518892443, 23707.52584948257, 23793.52412951766, 23818.0, 24018.63972040386, 18670.346537871654, 18855.340063097727, 19165.808341916403, 19188.520286992938, 19213.519662008002, 17010.48968530946, 17186.484405467854, 17464.12679366052, 17541.12294385301, 17685.734713979044, 18896.338628147947, 18974.0, 19227.51931201731, 19239.807601923803, 19372.0, 14871.776923346375, 15049.54851354461, 15111.31999060023, 15112.773308400163, 15137.848621513644, 18612.162452689932, 18776.74679038524, 18836.34072807445, 18881.527961801065, 18977.620447591245], "value": 12904.09504603931, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.9935500322497, 1369.1511263016926, 1371.807946887436, 1381.5233744358206, 1384.3562743324353, 13667.111637743552, 14215.0, 14231.217283049427, 14260.71478570428, 14277.643058923531, 13846.515371961983, 14703.632409189775, 14723.26383680817, 14732.558023259293, 14755.114693118421, 2689.542777727786, 2753.821001634897, 2754.2150487111176, 2754.820936639122, 2758.903438379657, 2592.053900326386, 2737.5209338365808, 2740.945181096377, 2752.0, 2754.2701184186208, 2696.514627367073, 2728.140635699758, 2743.27303264635, 2757.7793776497933, 2761.8066735328534, 2674.358154043035, 2676.0633778177676, 2702.4459985702883, 2718.306831757899, 2751.834889906612, 2553.19574334086, 2566.1275166443497, 2587.2884956636885, 2588.314096764359, 2588.8058395620405, 13671.111377760513, 14001.369938352696, 14005.229712365815, 14007.789883151761, 14013.439462421491, 7533.322001019879, 7772.727954521564, 7791.571463569501, 7799.688012479494, 7831.960840195819, 5071.290019397287, 5192.1432963560965, 5281.419043905167, 5287.788488460457, 5305.734713264322, 1759.463363674079, 1815.5007372972407, 1824.7080467125197, 1860.3209828412666, 1869.3550724999852, 21333.89333053356, 23176.420589485228, 23559.29322120322, 23582.764172358227, 23656.40858978522, 6474.546781725234, 6675.3324667533825, 6992.93007069933, 7205.963970180115, 7220.494565380373, 18769.90615046916, 18782.436526904214, 18784.436466905743, 18801.623967520845, 18810.0, 7764.495307804975, 7962.761117166494, 7963.323117535075, 7974.202579742096, 8295.0, 7330.083739532559, 7351.264873512606, 7379.003834482383, 7390.260973902674, 7397.297256760599, 5465.836024919259, 5569.10894256917, 5580.581456390787, 5641.75881306112, 5760.971195143998, 1700.532353602769, 1718.6390857919728, 1726.2749645149067, 1741.7822772153481, 1757.7627020352345, 895.0, 920.1120918313859, 930.4510338900081, 934.9345545811727, 943.4905151218403, 7506.737264195727, 7535.1334596521, 7544.132424771112, 7544.509606875646, 7565.243475652501, 696.6412297666706, 726.4852781949588, 732.2787054751066, 738.265425901221, 743.2864450127938, 4840.419149702058, 5659.490645841894, 5792.88414231713, 5839.0, 5992.101184822312, 19551.0, 19645.31241406544, 19977.900110499646, 19993.900335481398, 20003.899980500006, 18554.165062572218, 19601.313954011828, 19634.803651963583, 19681.90159049224, 19682.60634787325, 10252.58989640406, 10487.947560262302, 10522.842157367646, 10551.68344949668, 10579.576816927236, 8546.487210767375, 8585.742427727178, 8616.78458038554, 8619.956900215459, 8690.869636955576, 16591.170441477636, 16976.23606937666, 17021.659566808346, 17208.13959302005, 17299.13504324804, 16997.49007529776, 17068.23192956295, 17284.740728888824, 17397.30410783605, 17649.470515884543, 11471.827922581, 11678.0, 11697.649070527897, 11700.35648039366, 11712.121590880548, 12835.87164128384, 13008.544700935421, 13155.473781048651, 13176.47294108225, 13249.205047697169, 10675.199360048182, 10794.514246858753, 10882.347059176474, 11036.503357348778, 11208.27146235492, 10590.099841513558, 10605.363678179334, 10614.893851061699, 10764.838527422251, 10842.728931776768, 9642.325037247325, 9752.0, 9754.365966212174, 9759.219262458844, 9759.658411955548, 7119.57934302319, 7177.210506844139, 7245.985562021427, 7273.127224733066, 7301.306375894282, 7121.928780712126, 7148.64256787169, 7179.138503379628, 7194.928050719426, 7340.2292759261645], "value": 6500.308198777879, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.10676462074406, 496.43902390298956, 497.4975274972277, 507.40886866800156, 510.2346480279578, 6175.3207147213825, 6186.4432201101945, 6228.22147231596, 6233.7818176363835, 6237.313895471494, 6038.365971572976, 6080.817575472732, 6103.755849766004, 6123.173371594833, 6127.142200091991, 5008.649394542384, 5022.296878437003, 5055.140626093573, 5056.002878862847, 5071.7210553419545, 4774.856754297377, 4884.487128851472, 4891.192953162724, 4953.876153096184, 4995.975020124894, 4827.203511420611, 4846.466888642247, 4854.975725121387, 4900.736359367355, 4941.283513890484, 4870.634702397334, 4886.63350248731, 4920.778564964549, 4946.576177672914, 4971.950280497185, 4396.78016099197, 4414.359917811931, 4436.578525040116, 4469.240229161051, 4472.373867658531, 5999.640021598718, 6001.759929602855, 6043.1841701370195, 6103.786367477117, 6152.723127459275, 5596.608237423382, 5619.887602247933, 5661.0, 5683.914741278884, 5715.685637289947, 6605.240397354318, 6613.93386066138, 6621.0, 6630.701618427182, 6640.177924382118, 4785.856424307276, 4830.951690483085, 4838.637102217348, 4842.757862106881, 4865.975670121662, 6554.279029306773, 6593.571417857824, 6666.133642487346, 6773.2070040776825, 6861.55399899005, 4902.779374928136, 4910.508949105132, 4917.336159618477, 4924.3352147460355, 4936.456989731127, 5004.549590536869, 5014.874628134253, 5025.84922452327, 5041.2438134280155, 5084.949150508522, 5592.944070559242, 5625.6905870176715, 5650.237217975603, 5689.288838895138, 5696.829095127153, 6509.511786616023, 6527.3472652734345, 6548.410643042053, 6585.703643335965, 6610.173728283965, 1754.4210410564483, 1755.2803350626436, 1772.5834428909195, 1773.1045821860137, 1774.9201285942163, 1121.685927940178, 1192.3740036480917, 1194.6057800925673, 1206.4088596587785, 1207.3299318878107, 5695.800646977336, 5712.7714891403875, 5740.0815869460685, 5813.0, 5820.854478638068, 6672.666366681647, 6682.389151485557, 6729.25978142404, 6744.527883048139, 6798.762043328558, 5719.655880867993, 5778.16216648592, 5797.333306669787, 5797.797077102366, 5846.532277417797, 3551.786892786441, 3589.730770192246, 3594.281143771256, 3617.8371973261264, 3662.1943172502006, 6551.4103730664465, 6555.3772391622715, 6560.671966401661, 6562.343765623399, 6586.209654841354, 6843.270417622408, 6856.931430685729, 6949.89575156373, 6975.82560435983, 6994.230634630187, 6443.5551958594915, 6449.709763060768, 6512.251091124582, 6519.282878883224, 6527.608343499311, 6677.532572719863, 6715.0, 6734.696938637675, 6767.086443330088, 6783.796486105425, 6634.535582509371, 6637.601743895381, 6676.365745254193, 6689.431398331197, 6729.192496900403, 5787.479126878601, 6557.311482294291, 6557.508186885907, 6562.5406221564035, 6564.540482166202, 4946.950530494649, 4954.653174277766, 4964.826231081895, 4965.925511117264, 4985.351904252424, 6503.6422996733745, 6638.867222655424, 6649.966750166218, 6753.86492270142, 6769.93230067693, 6464.741410343721, 6477.578957367756, 6505.869882602226, 6511.34886511345, 6584.604923704593, 6549.508786840911, 6552.508561857784, 6577.50668699859, 6579.243387010555, 6622.271550129578, 6340.49276057905, 6387.688310390561, 6409.679516024274, 6463.547551671526, 6526.4452521536205, 6590.835229119119, 6599.439047681001, 6599.7360105595235, 6600.399600399601, 6608.306127856698, 6300.440251258025, 6349.3650634937985, 6382.712777924911, 6382.776602818879, 6400.167978162809], "value": 5032.645704022685, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.5355054719898, 856.4261944497187, 858.7337925243176, 891.835010523053, 907.3240435875272, 18284.817151828476, 18923.432297031093, 19527.609447811035, 19563.315283965065, 19617.31339403121, 20921.16315347388, 21470.141194352207, 21517.24689635863, 21751.564968700615, 21789.019494122764, 1020.1736593359384, 1059.2426415113232, 1062.6387028410338, 1063.122923588043, 1063.7181146996052, 978.0122076702511, 1064.2071656615833, 1065.137238836541, 1065.4725910674188, 1072.244067932106, 1113.5156207049918, 1114.2033446086048, 1114.6210288501907, 1114.6711720042606, 1118.0217309853879, 1098.527633117759, 1103.47584897174, 1105.2263415609066, 1107.9058280046206, 1108.2187058124043, 816.1308206759841, 818.705266104202, 819.1644522586928, 819.5533434278333, 819.942604017719, 19662.508437289183, 20370.796292037037, 20461.18155273787, 20569.280075197446, 20658.586828263502, 21753.891230543904, 21792.673109903204, 22181.223657172082, 22337.55324893493, 22665.77334226653, 16937.66124677512, 17325.306987720476, 17349.306027758874, 17389.82610173895, 17601.207945642476, 3587.2287458196615, 3592.263585964871, 3596.7841929484052, 3599.6220396858216, 3600.315939971423, 129086.0, 132328.33835830857, 132871.0, 133803.33098334447, 134015.0, 17141.400050998156, 17194.914025429793, 17605.119744012747, 17734.11329433523, 17749.467515974542, 31055.378892422024, 32027.35945281081, 32328.67671323304, 32430.675693243236, 32455.837720811243, 7902.288794008567, 7973.601319934096, 7980.920190798018, 8234.423590348619, 8333.208345207195, 3829.4064420014943, 4385.51759306476, 4391.912161756811, 4406.228909940747, 4414.448193975753, 5345.358556973188, 5446.645968012067, 5517.61376703635, 5519.69641669712, 5526.336839579276, 1009.9646512372032, 1021.9233557483218, 1028.6296933104077, 1029.5881647341123, 1031.7626945802508, 668.8695704337705, 691.7094820175538, 694.4270976444453, 694.6700317349218, 706.3219309462974, 11325.603603873826, 11597.246178998337, 12165.330906800034, 12204.572839950737, 12865.356732163355, 660.4122331125302, 668.6656671664168, 674.4402146218627, 689.2521614048813, 696.1089805049545, 5618.336780472198, 5659.5755318351285, 5728.112142617901, 5840.737166827503, 5856.180134781134, 95591.0, 98775.0, 101632.49183754034, 102360.48819755853, 103288.0, 88926.55536722275, 91473.54263228775, 92473.5376323114, 93487.0, 93506.0, 76402.23597763952, 77225.0, 77729.0, 77998.22001779909, 79507.60246198732, 12572.1199516033, 13162.934185329012, 13183.209007459584, 13427.731445370842, 13463.990200734788, 34845.0, 36010.27979440449, 36744.63255367412, 37006.6299337014, 37570.43644345392, 45152.54847451615, 45386.09227815491, 45386.77306613446, 45825.312620311386, 46428.767856160506, 18163.636727265115, 18753.156107975447, 18781.6243675123, 18804.247830086642, 19003.714944276122, 21115.57768844584, 21131.471713206676, 21354.14583416646, 21620.243291484723, 22828.771712282665, 19451.61096778028, 19605.11776970011, 19981.100850462055, 20025.899870500554, 20087.698684520037, 20040.799592003892, 20614.896925515277, 20623.79376206219, 20670.689939651216, 20719.378418647466, 18474.353397631017, 19260.711089333392, 19497.0, 19573.314933977243, 19893.60212795765, 12971.35143242853, 13849.723005539632, 14116.29418529049, 14135.64660883486, 14558.708825823212, 13554.93222533881, 14423.42306307736, 14498.782518262024, 14550.708985820012, 14871.776923345942], "value": 9215.955558271033, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 101.0, 107.0, 107.0, 110.0, 107.0, 99.0, 110.0, 110.0], "value": 105.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 128.0, 156.0, 167.0, 123.0, 153.0, 149.0, 119.0, 177.0, 119.0], "value": 143.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 162.0, 162.0, 164.0, 161.0, 168.0, 166.0, 161.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 74.0, 180.0, 76.0, 76.0, 74.0, 74.0, 151.0, 75.0], "value": 93.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 128.0, 79.0, 79.0, 79.0, 123.0, 79.0, 80.0, 129.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 115.0, 114.0, 114.0, 114.0, 115.0, 113.0, 113.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 150.0, 153.0, 151.0, 150.0, 150.0, 149.0, 151.0, 150.0, 149.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 94.0, 98.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 53.0, 55.0, 58.0, 54.0, 54.0, 54.0, 53.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 54.0, 56.0, 54.0, 55.0, 54.0, 54.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 88.0, 78.0, 79.0, 79.0, 79.0, 79.0, 88.0, 78.0, 78.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 130.0, 132.0, 140.0, 122.0, 125.0, 134.0, 134.0, 123.0, 176.0], "value": 134.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 174.0, 180.0, 181.0, 189.0, 173.0, 176.0, 177.0, 176.0, 177.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 67.0, 66.0, 69.0, 65.0, 65.0, 65.0, 66.0, 64.0, 65.0], "value": 66.9, "unit": "score"}]}]} 05:58:03 INFO - Return code: 0 05:58:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:58:03 INFO - # TBPL SUCCESS # 05:58:03 INFO - Running post-action listener: _package_coverage_data 05:58:03 INFO - Running post-action listener: _resource_record_post_action 05:58:03 INFO - [mozharness: 2017-08-07 12:58:03.677820Z] Finished run-tests step (success) 05:58:03 INFO - Running post-run listener: _resource_record_post_run 05:58:03 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 147456; Write bytes: 432205824; Read time: 40; Write time: 275416 05:58:03 INFO - TinderboxPrint: CPU usage
13.1% 05:58:03 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 40 05:58:03 INFO - TinderboxPrint: I/O write bytes / time
432,205,824 / 275,416 05:58:03 INFO - TinderboxPrint: CPU idle
8,248.5 (86.9%) 05:58:03 INFO - TinderboxPrint: CPU user
1,201.5 (12.7%) 05:58:03 INFO - TinderboxPrint: Swap in / out
0 / 0 05:58:03 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 107278336; Read time: 0; Write time: 81548 05:58:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:58:03 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 143360; Write bytes: 324927488; Read time: 28; Write time: 193868 05:58:04 INFO - Running post-run listener: _upload_blobber_files 05:58:04 INFO - Blob upload gear active. 05:58:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:58:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:58:04 INFO - (blobuploader) - INFO - Open directory for files ... 05:58:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:58:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:58:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:05 INFO - (blobuploader) - INFO - Done attempting. 05:58:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:58:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:58:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:12 INFO - (blobuploader) - INFO - Done attempting. 05:58:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:58:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:58:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:13 INFO - (blobuploader) - INFO - Done attempting. 05:58:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:58:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:58:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:58:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:58:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:58:14 INFO - (blobuploader) - INFO - Done attempting. 05:58:14 INFO - (blobuploader) - INFO - Iteration through files over. 05:58:14 INFO - Return code: 0 05:58:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:58:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:58:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a10164a9ae7ae0a93fef2a85e5da477e581f36ab8601f635aa4229dc53dcb84d8da039f680065a687e69be3ab93b0267dc788d5cae9d5e53b0510f68849f9445", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ecaab1871f9875f57105bf6e025fe799b5e9e4eb74878dc5ce8961ae0b2ec5a1a4002a841df407343d9b1368d69ad567d245c28feef61d75d3041a02ced9622", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8dc949dd10029377510287ec164fef6355f980fcf56b68e84e2e20893ea65e5cc01300f537d3115af84d4980617694a21bab18ba14b8dce4366df6b92389827e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d95d160feb75283759a31b819d3e5b4a6435eba95aa09ce27d12d4a4c4e6705bc6cb97a07a009925454c3dd45cba3b620de3d4bd6709f6cde9792de88e9014f3"} 05:58:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:58:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:58:14 INFO - Contents: 05:58:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a10164a9ae7ae0a93fef2a85e5da477e581f36ab8601f635aa4229dc53dcb84d8da039f680065a687e69be3ab93b0267dc788d5cae9d5e53b0510f68849f9445", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ecaab1871f9875f57105bf6e025fe799b5e9e4eb74878dc5ce8961ae0b2ec5a1a4002a841df407343d9b1368d69ad567d245c28feef61d75d3041a02ced9622", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8dc949dd10029377510287ec164fef6355f980fcf56b68e84e2e20893ea65e5cc01300f537d3115af84d4980617694a21bab18ba14b8dce4366df6b92389827e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d95d160feb75283759a31b819d3e5b4a6435eba95aa09ce27d12d4a4c4e6705bc6cb97a07a009925454c3dd45cba3b620de3d4bd6709f6cde9792de88e9014f3"} 05:58:14 INFO - Running post-run listener: copy_logs_to_upload_dir 05:58:14 INFO - Copying logs to upload dir... 05:58:14 INFO - mkdir: /builds/slave/test/build/upload/logs 05:58:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.506969 ========= master_lag: 0.95 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-07 05:58:15.464599) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-07 05:58:15.469018) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a10164a9ae7ae0a93fef2a85e5da477e581f36ab8601f635aa4229dc53dcb84d8da039f680065a687e69be3ab93b0267dc788d5cae9d5e53b0510f68849f9445", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ecaab1871f9875f57105bf6e025fe799b5e9e4eb74878dc5ce8961ae0b2ec5a1a4002a841df407343d9b1368d69ad567d245c28feef61d75d3041a02ced9622", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8dc949dd10029377510287ec164fef6355f980fcf56b68e84e2e20893ea65e5cc01300f537d3115af84d4980617694a21bab18ba14b8dce4366df6b92389827e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d95d160feb75283759a31b819d3e5b4a6435eba95aa09ce27d12d4a4c4e6705bc6cb97a07a009925454c3dd45cba3b620de3d4bd6709f6cde9792de88e9014f3"} build_url:https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011226 build_url: 'https://queue.taskcluster.net/v1/task/CTkm8jlKQvKlvnvlEGpMow/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a10164a9ae7ae0a93fef2a85e5da477e581f36ab8601f635aa4229dc53dcb84d8da039f680065a687e69be3ab93b0267dc788d5cae9d5e53b0510f68849f9445", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0ecaab1871f9875f57105bf6e025fe799b5e9e4eb74878dc5ce8961ae0b2ec5a1a4002a841df407343d9b1368d69ad567d245c28feef61d75d3041a02ced9622", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8dc949dd10029377510287ec164fef6355f980fcf56b68e84e2e20893ea65e5cc01300f537d3115af84d4980617694a21bab18ba14b8dce4366df6b92389827e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d95d160feb75283759a31b819d3e5b4a6435eba95aa09ce27d12d4a4c4e6705bc6cb97a07a009925454c3dd45cba3b620de3d4bd6709f6cde9792de88e9014f3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-07 05:58:15.501430) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:58:15.501765) ========= 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=2c5dd2b0a310ecf02fd87158000001b4-1502109332.271363-1073876135 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004583 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-07 05:58:15.555399) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-07 05:58:15.557729) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-07 05:58:15.558061) ========= ========= Total master_lag: 1.59 =========