builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-079 starttime: 1502193805.93 results: success (0) revision: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:25.929558) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:25.929954) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:25.930253) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:25.946831) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:25.947109) ========= 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=1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-08 05:03:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.7M=0s 2017-08-08 05:03:26 (42.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111751 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:26.072358) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:26.072691) ========= 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=1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019480 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:26.123701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-08 05:03:26.123997) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a921bfb8a2cf3db4d9edebe9b35799a3f9d035da --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 a921bfb8a2cf3db4d9edebe9b35799a3f9d035da --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=1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-08 05:03:26,570 truncating revision to first 12 chars 2017-08-08 05:03:26,570 Setting DEBUG logging. 2017-08-08 05:03:26,570 attempt 1/10 2017-08-08 05:03:26,570 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a921bfb8a2cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-08 05:03:27,464 unpacking tar archive at: mozilla-central-a921bfb8a2cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.227795 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-08 05:03:27.364579) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:27.364919) ========= script_repo_revision: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:27.365332) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:27.365602) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-08 05:03:27.373660) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-08-08 05:03:27.374010) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:03:27 INFO - MultiFileLogger online at 20170808 05:03:27 in /builds/slave/test 05:03:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:03:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:03:27 INFO - {'append_to_log': False, 05:03:27 INFO - 'base_work_dir': '/builds/slave/test', 05:03:27 INFO - 'blob_upload_branch': 'Firefox', 05:03:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:03:27 INFO - 'branch': 'Firefox', 05:03:27 INFO - 'buildbot_json_path': 'buildprops.json', 05:03:27 INFO - 'code_coverage': False, 05:03:27 INFO - 'config_files': ('talos/linux_config.py',), 05:03:27 INFO - 'default_actions': ('clobber', 05:03:27 INFO - 'read-buildbot-config', 05:03:27 INFO - 'download-and-extract', 05:03:27 INFO - 'populate-webroot', 05:03:27 INFO - 'create-virtualenv', 05:03:27 INFO - 'install', 05:03:27 INFO - 'setup-mitmproxy', 05:03:27 INFO - 'run-tests'), 05:03:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:03:27 INFO - 'disable_ccov_upload': False, 05:03:27 INFO - 'download_minidump_stackwalk': True, 05:03:27 INFO - 'download_symbols': 'ondemand', 05:03:27 INFO - 'enable_stylo': False, 05:03:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:03:27 INFO - 'tooltool.py': '/tools/tooltool.py', 05:03:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:03:27 INFO - '/tools/misc-python/virtualenv.py')}, 05:03:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:03:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:03:27 INFO - 'gecko_profile': False, 05:03:27 INFO - 'gecko_profile_interval': 0, 05:03:27 INFO - 'installer_path': 'installer.exe', 05:03:27 INFO - 'log_level': 'info', 05:03:27 INFO - 'log_name': 'talos', 05:03:27 INFO - 'log_to_console': True, 05:03:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:03:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:03:27 INFO - 'opt_config_files': (), 05:03:27 INFO - 'pip_index': False, 05:03:27 INFO - 'suite': 'dromaeojs-e10s', 05:03:27 INFO - 'system_bits': '32', 05:03:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:03:27 INFO - 'title': 'talos-linux64-ix-079', 05:03:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:03:27 INFO - 'use_talos_json': True, 05:03:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:03:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:03:27 INFO - 'work_dir': 'build'} 05:03:27 INFO - [mozharness: 2017-08-08 12:03:27.923258Z] Running clobber step. 05:03:27 INFO - Running pre-action listener: _resource_record_pre_action 05:03:27 INFO - Running main action method: clobber 05:03:27 INFO - rmtree: /builds/slave/test/build 05:03:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:03:28 INFO - Running post-action listener: _resource_record_post_action 05:03:28 INFO - [mozharness: 2017-08-08 12:03:28.961076Z] Finished clobber step (success) 05:03:28 INFO - [mozharness: 2017-08-08 12:03:28.961181Z] Running read-buildbot-config step. 05:03:28 INFO - Running pre-action listener: _resource_record_pre_action 05:03:28 INFO - Running main action method: read_buildbot_config 05:03:28 INFO - Using buildbot properties: 05:03:28 INFO - { 05:03:28 INFO - "project": "", 05:03:28 INFO - "product": "firefox", 05:03:28 INFO - "who": "cbook@mozilla.com", 05:03:28 INFO - "installer_path": "public/build/target.tar.bz2", 05:03:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 05:03:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:03:28 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 05:03:28 INFO - "stage_platform": "linux64-stylo", 05:03:28 INFO - "basedir": "/builds/slave/test", 05:03:28 INFO - "buildnumber": 263, 05:03:28 INFO - "platform": "ubuntu64_hw_stylo", 05:03:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:03:28 INFO - "slavebuilddir": "test", 05:03:28 INFO - "taskId": "TVap8Km_TpefKXCOw8ZPEQ", 05:03:28 INFO - "branch": "mozilla-central", 05:03:28 INFO - "script_repo_revision": "production", 05:03:28 INFO - "revision": "a921bfb8a2cf3db4d9edebe9b35799a3f9d035da", 05:03:28 INFO - "slavename": "talos-linux64-ix-079", 05:03:28 INFO - "repo_path": "mozilla-central" 05:03:28 INFO - } 05:03:28 INFO - Finding installer, test and symbols from parent task. 05:03:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TVap8Km_TpefKXCOw8ZPEQ'}, attempt #1 05:03:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TVap8Km_TpefKXCOw8ZPEQ'}, attempt #1 05:03:29 INFO - Task dependencies: BYO0e8EGR6OPzlcMpTM4Pw 05:03:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw'}, attempt #1 05:03:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:03:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json 05:03:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip 05:03:30 INFO - Running post-action listener: _resource_record_post_action 05:03:30 INFO - [mozharness: 2017-08-08 12:03:30.357047Z] Finished read-buildbot-config step (success) 05:03:30 INFO - [mozharness: 2017-08-08 12:03:30.357269Z] Running download-and-extract step. 05:03:30 INFO - Running pre-action listener: _resource_record_pre_action 05:03:30 INFO - Running main action method: download_and_extract 05:03:30 INFO - mkdir: /builds/slave/test/build/tests 05:03: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:03:30 INFO - trying https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json 05:03:30 INFO - Downloading https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:03:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:03:31 INFO - Downloaded 989 bytes. 05:03:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:03:31 INFO - Using the following test package requirements: 05:03:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:03:31 INFO - u'common': [u'target.common.tests.zip'], 05:03:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:03:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:03:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:03:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:03:31 INFO - u'mozbase': [u'target.common.tests.zip'], 05:03:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:03:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:03:31 INFO - u'web-platform': [u'target.common.tests.zip', 05:03:31 INFO - u'target.web-platform.tests.tar.gz'], 05:03:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:03:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:03:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip 05:03:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:03:31 INFO - Fetch https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip into memory 05:03:32 INFO - Content-Length response header: 54284738 05:03:32 INFO - Bytes received: 54284738 05:03:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:03:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip 05:03:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:03:43 INFO - Fetch https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.common.tests.zip into memory 05:03:45 INFO - Content-Length response header: 54284738 05:03:45 INFO - Bytes received: 54284738 05:03:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.talos.tests.zip 05:03:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:03:57 INFO - Fetch https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.talos.tests.zip into memory 05:03:58 INFO - Content-Length response header: 14496008 05:03:58 INFO - Bytes received: 14496008 05:03: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:03:58 INFO - trying https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:03:58 INFO - Downloading https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:03:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:04:00 INFO - Downloaded 61893500 bytes. 05:04:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:00 INFO - mkdir: /builds/slave/test/properties 05:04:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:00 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:00 INFO - Contents: 05:04:00 INFO - build_url:https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 05:04:00 INFO - Running post-action listener: _resource_record_post_action 05:04:00 INFO - Running post-action listener: set_extra_try_arguments 05:04:00 INFO - [mozharness: 2017-08-08 12:04:00.130208Z] Finished download-and-extract step (success) 05:04:00 INFO - [mozharness: 2017-08-08 12:04:00.130307Z] Running populate-webroot step. 05:04:00 INFO - Running pre-action listener: _resource_record_pre_action 05:04:00 INFO - Running main action method: populate_webroot 05:04:00 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:04:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:04:00 INFO - 'tests': ['damp', 'tps']}, 05:04:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:04:00 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:04:00 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:04:00 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:00 INFO - 'ts_paint', 05:04:00 INFO - 'tpaint', 05:04:00 INFO - 'sessionrestore', 05:04:00 INFO - 'sessionrestore_many_windows', 05:04:00 INFO - 'sessionrestore_no_auto_restore', 05:04:00 INFO - 'tabpaint']}, 05:04:00 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:04:00 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:04:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:00 INFO - 'tsvgr_opacity', 05:04:00 INFO - 'tart', 05:04:00 INFO - 'tscrollx', 05:04:00 INFO - 'tsvg_static']}, 05:04:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:04:00 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:04:00 INFO - 'talos_options': ['--mitmproxy', 05:04:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:04:00 INFO - '--firstNonBlankPaint'], 05:04:00 INFO - 'tests': ['tp6_google', 05:04:00 INFO - 'tp6_youtube', 05:04:00 INFO - 'tp6_amazon', 05:04:00 INFO - 'tp6_facebook']}, 05:04:00 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:04:00 INFO - 'talos_options': ['--stylo', 05:04:00 INFO - '--mitmproxy', 05:04:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:04:00 INFO - '--firstNonBlankPaint'], 05:04:00 INFO - 'tests': ['tp6_google', 05:04:00 INFO - 'tp6_youtube', 05:04:00 INFO - 'tp6_amazon', 05:04:00 INFO - 'tp6_facebook']}, 05:04:00 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:04:00 INFO - 'talos_options': ['--stylo', 05:04:00 INFO - '--stylo-threads=1', 05:04:00 INFO - '--mitmproxy', 05:04:00 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:04:00 INFO - '--firstNonBlankPaint'], 05:04:00 INFO - 'tests': ['tp6_google', 05:04:00 INFO - 'tp6_youtube', 05:04:00 INFO - 'tp6_amazon', 05:04:00 INFO - 'tp6_facebook']}, 05:04:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:04:00 INFO - 'talos_options': ['--xperf_path', 05:04:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:00 INFO - 'tests': ['tp5n']}}} 05:04:00 INFO - Running post-action listener: _resource_record_post_action 05:04:00 INFO - [mozharness: 2017-08-08 12:04:00.138796Z] Finished populate-webroot step (success) 05:04:00 INFO - [mozharness: 2017-08-08 12:04:00.138887Z] Running create-virtualenv step. 05:04:00 INFO - Running pre-action listener: _resource_record_pre_action 05:04:00 INFO - Running main action method: create_virtualenv 05:04:00 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:00 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:04:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:00 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:04:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:00 INFO - Using real prefix '/usr' 05:04:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:00 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:02 INFO - Installing pip.................done. 05:04:02 INFO - Return code: 0 05:04:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:04:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:04:02 INFO - Reading from file tmpfile_stdout 05:04:02 INFO - Output received: 05:04:02 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:04:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:02 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:02 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:04:02 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:04:02 INFO - Using env: {'DISPLAY': ':0', 05:04:02 INFO - 'HOME': '/home/cltbld', 05:04:02 INFO - 'LANG': 'en_US.UTF-8', 05:04:02 INFO - 'LANGUAGE': 'en_US:en', 05:04:02 INFO - 'LOGNAME': 'cltbld', 05:04:02 INFO - 'MAIL': '/var/mail/cltbld', 05:04:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:02 INFO - 'MOZ_NO_REMOTE': '1', 05:04:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:02 INFO - 'NO_EM_RESTART': '1', 05:04:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:02 INFO - 'PWD': '/builds/slave/test', 05:04:02 INFO - 'SHELL': '/bin/bash', 05:04:02 INFO - 'SHLVL': '1', 05:04:02 INFO - 'TERM': 'linux', 05:04:02 INFO - 'TMOUT': '86400', 05:04:02 INFO - 'USER': 'cltbld', 05:04:02 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:02 INFO - '_': '/tools/buildbot/bin/python'} 05:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:03 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:04:03 INFO - Cleaning up... 05:04:03 INFO - Return code: 0 05:04:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:04: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:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04: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:04:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:03 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:03 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:04:03 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:04:03 INFO - Using env: {'DISPLAY': ':0', 05:04:03 INFO - 'HOME': '/home/cltbld', 05:04:03 INFO - 'LANG': 'en_US.UTF-8', 05:04:03 INFO - 'LANGUAGE': 'en_US:en', 05:04:03 INFO - 'LOGNAME': 'cltbld', 05:04:03 INFO - 'MAIL': '/var/mail/cltbld', 05:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:03 INFO - 'MOZ_NO_REMOTE': '1', 05:04:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:03 INFO - 'NO_EM_RESTART': '1', 05:04:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:03 INFO - 'PWD': '/builds/slave/test', 05:04:03 INFO - 'SHELL': '/bin/bash', 05:04:03 INFO - 'SHLVL': '1', 05:04:03 INFO - 'TERM': 'linux', 05:04:03 INFO - 'TMOUT': '86400', 05:04:03 INFO - 'USER': 'cltbld', 05:04:03 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:03 INFO - '_': '/tools/buildbot/bin/python'} 05:04:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:03 INFO - Downloading/unpacking psutil>=3.1.1 05:04:03 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:04:03 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:04:03 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:04:03 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:04:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:04 INFO - Installing collected packages: psutil 05:04:04 INFO - Running setup.py install for psutil 05:04:04 INFO - building 'psutil._psutil_linux' extension 05:04:04 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:04:04 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:04:04 INFO - building 'psutil._psutil_posix' extension 05:04:04 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:04:05 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:04:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:05 INFO - Successfully installed psutil 05:04:05 INFO - Cleaning up... 05:04:05 INFO - Return code: 0 05:04:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:05 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:05 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:04:05 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:04:05 INFO - Using env: {'DISPLAY': ':0', 05:04:05 INFO - 'HOME': '/home/cltbld', 05:04:05 INFO - 'LANG': 'en_US.UTF-8', 05:04:05 INFO - 'LANGUAGE': 'en_US:en', 05:04:05 INFO - 'LOGNAME': 'cltbld', 05:04:05 INFO - 'MAIL': '/var/mail/cltbld', 05:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:05 INFO - 'MOZ_NO_REMOTE': '1', 05:04:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:05 INFO - 'NO_EM_RESTART': '1', 05:04:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:05 INFO - 'PWD': '/builds/slave/test', 05:04:05 INFO - 'SHELL': '/bin/bash', 05:04:05 INFO - 'SHLVL': '1', 05:04:05 INFO - 'TERM': 'linux', 05:04:05 INFO - 'TMOUT': '86400', 05:04:05 INFO - 'USER': 'cltbld', 05:04:05 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:05 INFO - '_': '/tools/buildbot/bin/python'} 05:04:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:04:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:04:06 INFO - Installing collected packages: mozsystemmonitor 05:04:06 INFO - Running setup.py install for mozsystemmonitor 05:04:06 INFO - Successfully installed mozsystemmonitor 05:04:06 INFO - Cleaning up... 05:04:06 INFO - Return code: 0 05:04:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:04: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:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04: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:04:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:06 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:06 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:04:06 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:04:06 INFO - Using env: {'DISPLAY': ':0', 05:04:06 INFO - 'HOME': '/home/cltbld', 05:04:06 INFO - 'LANG': 'en_US.UTF-8', 05:04:06 INFO - 'LANGUAGE': 'en_US:en', 05:04:06 INFO - 'LOGNAME': 'cltbld', 05:04:06 INFO - 'MAIL': '/var/mail/cltbld', 05:04:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:06 INFO - 'MOZ_NO_REMOTE': '1', 05:04:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:06 INFO - 'NO_EM_RESTART': '1', 05:04:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:06 INFO - 'PWD': '/builds/slave/test', 05:04:06 INFO - 'SHELL': '/bin/bash', 05:04:06 INFO - 'SHLVL': '1', 05:04:06 INFO - 'TERM': 'linux', 05:04:06 INFO - 'TMOUT': '86400', 05:04:06 INFO - 'USER': 'cltbld', 05:04:06 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:06 INFO - '_': '/tools/buildbot/bin/python'} 05:04:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:07 INFO - Downloading/unpacking jsonschema==2.5.1 05:04: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:04: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:04: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:04: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:04:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:04:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:08 INFO - Downloading functools32-3.2.3-2.tar.gz 05:04:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:04:08 INFO - warning: no files found matching '*.txt' 05:04:08 INFO - no previously-included directories found matching 'build' 05:04:08 INFO - no previously-included directories found matching 'dist' 05:04:08 INFO - no previously-included directories found matching '.git*' 05:04:08 INFO - Installing collected packages: jsonschema, functools32 05:04:08 INFO - Running setup.py install for functools32 05:04:08 INFO - warning: no files found matching '*.txt' 05:04:08 INFO - no previously-included directories found matching 'build' 05:04:08 INFO - no previously-included directories found matching 'dist' 05:04:08 INFO - no previously-included directories found matching '.git*' 05:04:08 INFO - Successfully installed jsonschema functools32 05:04:08 INFO - Cleaning up... 05:04:08 INFO - Return code: 0 05:04:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:08 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:08 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:04:08 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:04:08 INFO - Using env: {'DISPLAY': ':0', 05:04:08 INFO - 'HOME': '/home/cltbld', 05:04:08 INFO - 'LANG': 'en_US.UTF-8', 05:04:08 INFO - 'LANGUAGE': 'en_US:en', 05:04:08 INFO - 'LOGNAME': 'cltbld', 05:04:08 INFO - 'MAIL': '/var/mail/cltbld', 05:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:08 INFO - 'MOZ_NO_REMOTE': '1', 05:04:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:08 INFO - 'NO_EM_RESTART': '1', 05:04:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:08 INFO - 'PWD': '/builds/slave/test', 05:04:08 INFO - 'SHELL': '/bin/bash', 05:04:08 INFO - 'SHLVL': '1', 05:04:08 INFO - 'TERM': 'linux', 05:04:08 INFO - 'TMOUT': '86400', 05:04:08 INFO - 'USER': 'cltbld', 05:04:08 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:08 INFO - '_': '/tools/buildbot/bin/python'} 05:04:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:04:08 INFO - Cleaning up... 05:04:08 INFO - Return code: 0 05:04:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:08 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:08 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:04:08 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:04:08 INFO - Using env: {'DISPLAY': ':0', 05:04:08 INFO - 'HOME': '/home/cltbld', 05:04:08 INFO - 'LANG': 'en_US.UTF-8', 05:04:08 INFO - 'LANGUAGE': 'en_US:en', 05:04:08 INFO - 'LOGNAME': 'cltbld', 05:04:08 INFO - 'MAIL': '/var/mail/cltbld', 05:04:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:08 INFO - 'MOZ_NO_REMOTE': '1', 05:04:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:08 INFO - 'NO_EM_RESTART': '1', 05:04:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:08 INFO - 'PWD': '/builds/slave/test', 05:04:08 INFO - 'SHELL': '/bin/bash', 05:04:08 INFO - 'SHLVL': '1', 05:04:08 INFO - 'TERM': 'linux', 05:04:08 INFO - 'TMOUT': '86400', 05:04:08 INFO - 'USER': 'cltbld', 05:04:08 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:08 INFO - '_': '/tools/buildbot/bin/python'} 05:04:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:10 INFO - Downloading blobuploader-1.2.4.tar.gz 05:04:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:04:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:04:10 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:04:10 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:04:10 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:04:10 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:04:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:04:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:04:10 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:04:10 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:04:10 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:04:10 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:04:10 INFO - Downloading docopt-0.6.1.tar.gz 05:04:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:04:10 INFO - Installing collected packages: blobuploader, requests, docopt 05:04:10 INFO - Running setup.py install for blobuploader 05:04:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:04:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:04:10 INFO - Running setup.py install for requests 05:04:11 INFO - Running setup.py install for docopt 05:04:11 INFO - Successfully installed blobuploader requests docopt 05:04:11 INFO - Cleaning up... 05:04:11 INFO - Return code: 0 05:04:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04: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:04:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04: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:04:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:11 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:11 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:04:11 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:04:11 INFO - Using env: {'DISPLAY': ':0', 05:04:11 INFO - 'HOME': '/home/cltbld', 05:04:11 INFO - 'LANG': 'en_US.UTF-8', 05:04:11 INFO - 'LANGUAGE': 'en_US:en', 05:04:11 INFO - 'LOGNAME': 'cltbld', 05:04:11 INFO - 'MAIL': '/var/mail/cltbld', 05:04:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:11 INFO - 'MOZ_NO_REMOTE': '1', 05:04:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:11 INFO - 'NO_EM_RESTART': '1', 05:04:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:11 INFO - 'PWD': '/builds/slave/test', 05:04:11 INFO - 'SHELL': '/bin/bash', 05:04:11 INFO - 'SHLVL': '1', 05:04:11 INFO - 'TERM': 'linux', 05:04:11 INFO - 'TMOUT': '86400', 05:04:11 INFO - 'USER': 'cltbld', 05:04:11 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:11 INFO - '_': '/tools/buildbot/bin/python'} 05:04:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:11 INFO - Running setup.py (path:/tmp/pip-5m4Jb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:11 INFO - Running setup.py (path:/tmp/pip-d_q22F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:11 INFO - Running setup.py (path:/tmp/pip-OvDedm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:11 INFO - Running setup.py (path:/tmp/pip-Vi5Ikw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:11 INFO - Running setup.py (path:/tmp/pip-aIuLOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:11 INFO - Running setup.py (path:/tmp/pip-Jfdh6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:11 INFO - Running setup.py (path:/tmp/pip-YpD34F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:11 INFO - Running setup.py (path:/tmp/pip-t0Ij5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:12 INFO - Running setup.py (path:/tmp/pip-lUK1WT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:12 INFO - Running setup.py (path:/tmp/pip-L86P29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:12 INFO - Running setup.py (path:/tmp/pip-y3fTUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:12 INFO - Running setup.py (path:/tmp/pip-LF7z5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:12 INFO - Running setup.py (path:/tmp/pip-YcUrel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:12 INFO - Running setup.py (path:/tmp/pip-4fqhAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:12 INFO - Running setup.py (path:/tmp/pip-BzaRXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:12 INFO - Running setup.py (path:/tmp/pip-3fxvGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:12 INFO - Running setup.py (path:/tmp/pip-BEHi3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:04:12 INFO - Running setup.py install for manifestparser 05:04:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:04:12 INFO - Running setup.py install for mozcrash 05:04:12 INFO - Running setup.py install for mozdebug 05:04:13 INFO - Running setup.py install for mozdevice 05:04:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:04:13 INFO - Running setup.py install for mozfile 05:04:13 INFO - Running setup.py install for mozhttpd 05:04:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:04:13 INFO - Running setup.py install for mozinfo 05:04:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:04:13 INFO - Running setup.py install for mozInstall 05:04:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:04:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:04:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:04:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:04:13 INFO - Running setup.py install for mozleak 05:04:13 INFO - Running setup.py install for mozlog 05:04:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:04:13 INFO - Running setup.py install for moznetwork 05:04:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:04:13 INFO - Running setup.py install for mozprocess 05:04:13 INFO - Running setup.py install for mozprofile 05:04:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:04:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:04:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:04:14 INFO - Running setup.py install for mozrunner 05:04:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:04:14 INFO - Running setup.py install for mozscreenshot 05:04:14 INFO - Running setup.py install for moztest 05:04:14 INFO - Running setup.py install for mozversion 05:04:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:04:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:04:14 INFO - Cleaning up... 05:04:14 INFO - Return code: 0 05:04:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:14 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:04:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:14 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:04:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:14 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:14 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:04:14 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:04:14 INFO - Using env: {'DISPLAY': ':0', 05:04:14 INFO - 'HOME': '/home/cltbld', 05:04:14 INFO - 'LANG': 'en_US.UTF-8', 05:04:14 INFO - 'LANGUAGE': 'en_US:en', 05:04:14 INFO - 'LOGNAME': 'cltbld', 05:04:14 INFO - 'MAIL': '/var/mail/cltbld', 05:04:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:14 INFO - 'MOZ_NO_REMOTE': '1', 05:04:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:14 INFO - 'NO_EM_RESTART': '1', 05:04:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:14 INFO - 'PWD': '/builds/slave/test', 05:04:14 INFO - 'SHELL': '/bin/bash', 05:04:14 INFO - 'SHLVL': '1', 05:04:14 INFO - 'TERM': 'linux', 05:04:14 INFO - 'TMOUT': '86400', 05:04:14 INFO - 'USER': 'cltbld', 05:04:14 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:14 INFO - '_': '/tools/buildbot/bin/python'} 05:04:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:14 INFO - Running setup.py (path:/tmp/pip-AN8aDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:14 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:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:14 INFO - Running setup.py (path:/tmp/pip-DkXl6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:14 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:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:14 INFO - Running setup.py (path:/tmp/pip-wT2JW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:14 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:04:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:14 INFO - Running setup.py (path:/tmp/pip-DqPOPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:15 INFO - Running setup.py (path:/tmp/pip-XyO4zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:15 INFO - Running setup.py (path:/tmp/pip-urJPdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:15 INFO - Running setup.py (path:/tmp/pip-ga8q2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:15 INFO - Running setup.py (path:/tmp/pip-yGldyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:15 INFO - Running setup.py (path:/tmp/pip-1rZGSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:15 INFO - Running setup.py (path:/tmp/pip-yW8xr7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:15 INFO - Running setup.py (path:/tmp/pip-E1HygD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:15 INFO - Running setup.py (path:/tmp/pip-LlrpKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:15 INFO - Running setup.py (path:/tmp/pip-vcOO5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:15 INFO - Running setup.py (path:/tmp/pip-AO7ARm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:15 INFO - Running setup.py (path:/tmp/pip-X3Obdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:15 INFO - Running setup.py (path:/tmp/pip-tWr8hn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:15 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:04:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:15 INFO - Running setup.py (path:/tmp/pip-jQUA2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:15 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:04:15 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:04:15 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:04:15 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:04:15 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:04:15 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:04:15 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:04:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:17 INFO - Downloading blessings-1.6.tar.gz 05:04:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:04:17 INFO - Installing collected packages: blessings 05:04:17 INFO - Running setup.py install for blessings 05:04:17 INFO - Successfully installed blessings 05:04:17 INFO - Cleaning up... 05:04:17 INFO - Return code: 0 05:04:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:04:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:17 INFO - Reading from file tmpfile_stdout 05:04:17 INFO - Current package versions: 05:04:17 INFO - argparse == 1.2.1 05:04:17 INFO - blessings == 1.6 05:04:17 INFO - blobuploader == 1.2.4 05:04:17 INFO - docopt == 0.6.1 05:04:17 INFO - functools32 == 3.2.3-2 05:04:17 INFO - jsonschema == 2.5.1 05:04:17 INFO - manifestparser == 1.1 05:04:17 INFO - mozInstall == 1.13 05:04:17 INFO - mozcrash == 1.0 05:04:17 INFO - mozdebug == 0.1 05:04:17 INFO - mozdevice == 0.51 05:04:17 INFO - mozfile == 1.2 05:04:17 INFO - mozhttpd == 0.7 05:04:17 INFO - mozinfo == 0.10 05:04:17 INFO - mozleak == 0.1 05:04:17 INFO - mozlog == 3.5 05:04:17 INFO - moznetwork == 0.27 05:04:17 INFO - mozprocess == 0.25 05:04:17 INFO - mozprofile == 0.28 05:04:17 INFO - mozrunner == 6.13 05:04:17 INFO - mozscreenshot == 0.1 05:04:17 INFO - mozsystemmonitor == 0.3 05:04:17 INFO - moztest == 0.8 05:04:17 INFO - mozversion == 1.4 05:04:17 INFO - psutil == 3.1.1 05:04:17 INFO - requests == 1.2.3 05:04:17 INFO - wsgiref == 0.1.2 05:04:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:17 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:04:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:17 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:04:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:17 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:17 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:04:17 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:04:17 INFO - Using env: {'DISPLAY': ':0', 05:04:17 INFO - 'HOME': '/home/cltbld', 05:04:17 INFO - 'LANG': 'en_US.UTF-8', 05:04:17 INFO - 'LANGUAGE': 'en_US:en', 05:04:17 INFO - 'LOGNAME': 'cltbld', 05:04:17 INFO - 'MAIL': '/var/mail/cltbld', 05:04:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:17 INFO - 'MOZ_NO_REMOTE': '1', 05:04:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:17 INFO - 'NO_EM_RESTART': '1', 05:04:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:17 INFO - 'PWD': '/builds/slave/test', 05:04:17 INFO - 'SHELL': '/bin/bash', 05:04:17 INFO - 'SHLVL': '1', 05:04:17 INFO - 'TERM': 'linux', 05:04:17 INFO - 'TMOUT': '86400', 05:04:17 INFO - 'USER': 'cltbld', 05:04:17 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:17 INFO - '_': '/tools/buildbot/bin/python'} 05:04:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:17 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:04:17 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:04:17 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:04:17 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:04:17 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:04:17 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:04:17 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:04:17 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:04:17 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:04:17 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:04:17 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:04:17 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:04:17 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:04:17 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:04:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:04:18 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:04:18 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:04:18 INFO - Installing collected packages: simplejson 05:04:18 INFO - Running setup.py install for simplejson 05:04:19 INFO - building 'simplejson._speedups' extension 05:04:19 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:04:19 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:04:19 INFO - Successfully installed simplejson 05:04:19 INFO - Cleaning up... 05:04:19 INFO - Return code: 0 05:04:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:04:19 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:04:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:19 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:04:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:19 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 0x24411f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x260da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27b0d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, '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': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', '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:04:19 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:04:19 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:04:19 INFO - Using env: {'DISPLAY': ':0', 05:04:19 INFO - 'HOME': '/home/cltbld', 05:04:19 INFO - 'LANG': 'en_US.UTF-8', 05:04:19 INFO - 'LANGUAGE': 'en_US:en', 05:04:19 INFO - 'LOGNAME': 'cltbld', 05:04:19 INFO - 'MAIL': '/var/mail/cltbld', 05:04:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:19 INFO - 'MOZ_NO_REMOTE': '1', 05:04:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:19 INFO - 'NO_EM_RESTART': '1', 05:04:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:19 INFO - 'PWD': '/builds/slave/test', 05:04:20 INFO - 'SHELL': '/bin/bash', 05:04:20 INFO - 'SHLVL': '1', 05:04:20 INFO - 'TERM': 'linux', 05:04:20 INFO - 'TMOUT': '86400', 05:04:20 INFO - 'USER': 'cltbld', 05:04:20 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:20 INFO - '_': '/tools/buildbot/bin/python'} 05:04:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:04:20 INFO - Cleaning up... 05:04:20 INFO - Return code: 0 05:04:20 INFO - Running post-action listener: _resource_record_post_action 05:04:20 INFO - Running post-action listener: _start_resource_monitoring 05:04:20 INFO - Starting resource monitoring. 05:04:20 INFO - [mozharness: 2017-08-08 12:04:20.263933Z] Finished create-virtualenv step (success) 05:04:20 INFO - [mozharness: 2017-08-08 12:04:20.264401Z] Running install step. 05:04:20 INFO - Running pre-action listener: _resource_record_pre_action 05:04:20 INFO - Running main action method: install 05:04:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:04:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:04:20 INFO - Reading from file tmpfile_stdout 05:04:20 INFO - Detecting whether we're running mozinstall >=1.0... 05:04:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:04:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:04:20 INFO - Reading from file tmpfile_stdout 05:04:20 INFO - Output received: 05:04:20 INFO - Usage: mozinstall [options] installer 05:04:20 INFO - Options: 05:04:20 INFO - -h, --help show this help message and exit 05:04:20 INFO - -d DEST, --destination=DEST 05:04:20 INFO - Directory to install application into. [default: 05:04:20 INFO - "/builds/slave/test"] 05:04:20 INFO - --app=APP Application being installed. [default: firefox] 05:04:20 INFO - mkdir: /builds/slave/test/build/application 05:04:20 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:04:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:04:38 INFO - Reading from file tmpfile_stdout 05:04:38 INFO - Output received: 05:04:38 INFO - /builds/slave/test/build/application/firefox/firefox 05:04:38 INFO - Running post-action listener: _resource_record_post_action 05:04:38 INFO - [mozharness: 2017-08-08 12:04:38.553459Z] Finished install step (success) 05:04:38 INFO - [mozharness: 2017-08-08 12:04:38.553753Z] Running setup-mitmproxy step. 05:04:38 INFO - Running pre-action listener: _resource_record_pre_action 05:04:38 INFO - Running main action method: setup_mitmproxy 05:04:38 INFO - Skipping: mitmproxy is not required 05:04:38 INFO - Running post-action listener: _resource_record_post_action 05:04:38 INFO - [mozharness: 2017-08-08 12:04:38.554905Z] Finished setup-mitmproxy step (success) 05:04:38 INFO - [mozharness: 2017-08-08 12:04:38.555128Z] Running run-tests step. 05:04:38 INFO - Running pre-action listener: _resource_record_pre_action 05:04:38 INFO - Running pre-action listener: _set_gcov_prefix 05:04:38 INFO - Running main action method: run_tests 05:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:04:38 INFO - Python 2.7.3 05:04:38 INFO - Return code: 0 05:04:38 INFO - grabbing minidump binary from tooltool 05:04:38 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:04:38 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 0x27adeb0>, '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 0x27aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27af240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:04:38 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:04:38 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:04:38 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:04:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:04:38 INFO - Return code: 0 05:04:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:04:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:04:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:04:38 INFO - ENV: RUST_BACKTRACE is now 1 05:04:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:04:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:04:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --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:04:38 INFO - Using env: {'DISPLAY': ':0', 05:04:38 INFO - 'HOME': '/home/cltbld', 05:04:38 INFO - 'LANG': 'en_US.UTF-8', 05:04:38 INFO - 'LANGUAGE': 'en_US:en', 05:04:38 INFO - 'LOGNAME': 'cltbld', 05:04:38 INFO - 'MAIL': '/var/mail/cltbld', 05:04:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:04:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:38 INFO - 'MOZ_NO_REMOTE': '1', 05:04:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:04:38 INFO - 'NO_EM_RESTART': '1', 05:04:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:38 INFO - 'PWD': '/builds/slave/test', 05:04:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:04:38 INFO - 'RUST_BACKTRACE': '1', 05:04:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:04:38 INFO - 'SHELL': '/bin/bash', 05:04:38 INFO - 'SHLVL': '1', 05:04:38 INFO - 'STYLO_FORCE_ENABLED': '1', 05:04:38 INFO - 'TERM': 'linux', 05:04:38 INFO - 'TMOUT': '86400', 05:04:38 INFO - 'USER': 'cltbld', 05:04:38 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622', 05:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:38 INFO - '_': '/tools/buildbot/bin/python'} 05:04:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--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:04:38 INFO - mozversion application_buildid: 20170808111841 05:04:38 INFO - mozversion application_changeset: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da 05:04:38 INFO - mozversion application_display_name: Nightly 05:04:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:04:38 INFO - mozversion application_name: Firefox 05:04:38 INFO - mozversion application_remotingname: firefox 05:04:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:04:38 INFO - mozversion application_vendor: Mozilla 05:04:38 INFO - mozversion application_version: 57.0a1 05:04:38 INFO - mozversion platform_buildid: 20170808111841 05:04:38 INFO - mozversion platform_changeset: a921bfb8a2cf3db4d9edebe9b35799a3f9d035da 05:04:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:04:38 INFO - mozversion platform_version: 57.0a1 05:04:38 INFO - using testdate: 1502193878 05:04:38 INFO - actual date: 1502193878 05:04:38 INFO - starting webserver on 'localhost:60084' 05:04:38 INFO - SUITE-START | Running 2 tests 05:04:38 INFO - TEST-START | dromaeo_css 05:04:38 INFO - Initialising browser for dromaeo_css test... 05:04:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60084/getInfo.html -profile /tmp/tmpBZpBXl/profile 05:04:38 INFO - TEST-INFO | started process 9096 (/builds/slave/test/build/application/firefox/firefox http://localhost:60084/getInfo.html) 05:04:45 INFO - TEST-INFO | 9096: exit 0 05:04:45 INFO - Browser initialized. 05:04:45 INFO - Running cycle 1/1 for dromaeo_css test... 05:04:45 INFO - TEST-INFO | started process 9319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBZpBXl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:46 INFO - PID 9319 | 05:04:46 INFO - PID 9319 | (/builds/slave/test/build/application/firefox/firefox:9373): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:04:46 INFO - PID 9319 | 05:04:57 INFO - PID 9319 | RSS: Main: 170008576 05:04:57 INFO - PID 9319 | 05:07:57 INFO - PID 9319 | Cycle 1(1): loaded http://localhost:60084/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60084/tests/dromaeo/cssquery-ext.html) 05:07:58 INFO - PID 9319 | RSS: Main: 175235072 05:07:58 INFO - PID 9319 | 05:11:00 INFO - PID 9319 | Cycle 1(1): loaded http://localhost:60084/tests/dromaeo/cssquery-ext.html (next: http://localhost:60084/tests/dromaeo/cssquery-jquery.html) 05:11:00 INFO - PID 9319 | RSS: Main: 173043712 05:11:00 INFO - PID 9319 | 05:14:02 INFO - PID 9319 | Cycle 1(1): loaded http://localhost:60084/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60084/tests/dromaeo/cssquery-mootools.html) 05:14:03 INFO - PID 9319 | RSS: Main: 174972928 05:14:03 INFO - PID 9319 | 05:17:05 INFO - PID 9319 | Cycle 1(1): loaded http://localhost:60084/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60084/tests/dromaeo/cssquery-prototype.html) 05:17:05 INFO - PID 9319 | RSS: Main: 173412352 05:17:05 INFO - PID 9319 | 05:20:09 INFO - PID 9319 | Cycle 1(1): loaded http://localhost:60084/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60084/tests/dromaeo/cssquery-yui.html) 05:20:10 INFO - PID 9319 | RSS: Main: 176570368 05:20:10 INFO - PID 9319 | 05:23:11 INFO - PID 9319 | Cycle 1(1): loaded http://localhost:60084/tests/dromaeo/cssquery-yui.html (next: http://localhost:60084/tests/dromaeo/cssquery-dojo.html) 05:23:12 INFO - PID 9319 | RSS: Main: 177508352 05:23:12 INFO - PID 9319 | 05:23:12 INFO - PID 9319 | __start_tp_report 05:23:12 INFO - PID 9319 | _x_x_mozilla_page_load 05:23:12 INFO - PID 9319 | _x_x_mozilla_page_load_details 05:23:12 INFO - PID 9319 | |i|pagename|runs| 05:23:12 INFO - PID 9319 | |0;dojo.html;3177.3645270945817;3186.060112266881;3207.647158812531;3213.3894560033586;3243.8215898125595;6198.25620925489;6207.875842483148;6298.779542716006;6307;6371.713272902719;6304.243490781101;6326.462250708695;6389.393007664276;6401.071844582532;6421.903671444932;5772.913406298909;5810.1284807279035;5845.707714614274;5894.056950887837;5911.9113213301835;5645.54835613152;5725.685087320196;5744.4829965303115;5758.395368486312;5820.330661973885;5811.476967072963;5815.970920145414;5818.3308919474375;5819.243498345209;5820.476157145856;5751.597388182829;5771.855703607402;5823.592348535604;5846.093855452412;5848.444397782204;5633.070543360341;5676.205331253628;5695.629784064023;5713.8285851424525;5763.308402991626;6208.2239720034995;6230.2523697156175;6247.843803904893;6258.030005349178;6285.717142728588;6748.29142939994;6763.188417389895;6814.522983391164;6822.4200942919415;6845.5550389224545;6734.191896972346;6753.45972322213;6806.115205023365;6847.554908930903;6855.622940738307;6249.218847644044;6283.528735344913;6284.497240220817;6290.276618188876;6303.873922521524;6895.241523432418;6914.723411063501;6916.861662766716;6917.89623155653;6926.2034865990045;6586.7035983380865;6604.3725846044545;6674.83312917181;6676.232233293136;6676.699548520328;6806.42145417645;6838;6842;6843.863122737517;6859.965700171567;6633.104530888365;6709.5303328767495;6729.22613899398;6804.9319506804295;6811.080504131879;6628.138342015507;6749.0888730020815;6759.932400675931;6764.4926630502905;6815.2162501312;5469.042917489423;5473;5474.562035037187;5480.150576660544;5504.889902202013;4639.582437580634;4674.97662511692;4681.95318046822;4699.577038066591;4745.169595320874;5807.970960145172;5816.272965879265;5890.705464726832;5894.17481552583;5907.2911250650195;6719.529632925746;6810.557313774589;6816.965915170492;6821.1814582250445;6870.31296870319;5841.06542953134;5844.766209351664;5848.035074212792;5848.824535263949;5863.091220860773;5882.323532793784;5950.880982380415;5960.791372301948;5962.19510366095;5986.640801551921;6555.3772391622715;6564.245111812107;6567.901481477782;6572.474202063823;6577.86844263112;5690.3456102548735;6663.433608143265;6746.763863264762;6830.863382732218;6912.930870691229;6519.185101862267;6554.967225163844;6582.473402127723;6656.500762442933;6677.3656502632175;6646.5347425681675;6653.933460665331;6656.500762442933;6686.431653309329;6698.5980841149685;6812.352826481477;6819.693113809792;6828.795136145924;6830.043793868763;6834.316568343126;6515.869682606416;6576.079348891063;6576.112224849775;6597.637129957899;6631.104800851823;6610.305917878553;6618.569792963442;6665.066890635412;6699.933000670126;6709.530332876652;6460.870782584416;6497.967510162419;6520.576162549609;6613.570117942319;6614.834629134118;6590.868182636224;6621.801345959629;6750.05499230118;6760.797176084725;6766.18805743314;6405.455536279468;6422;6425.710843011982;6516.869662606816;6520.119783829123 05:23:12 INFO - PID 9319 | |1;ext.html;771.3906014248744;829.2619568583959;852.8891244138262;855.5166331022975;1150.4707834396177;30331.241718957037;41741.79129104358;42894.35658465126;42974.71075867721;42997;42920;43054.354184687254;43155;43157.136857262834;43205.567944320544;7510.849783004337;7795.298423141916;7797.649105790253;7799.532028078305;7803.063632364124;7598.126215485235;7711.2288771123;7737.187595302496;7737.845243095135;7739.419543534229;7678.692852285902;7741.032370953631;7754.495957762728;7762.495437796525;7762.805929851771;7687.615619219045;7732.652030658605;7739.226077392215;7748.108967468757;7751.263629955145;7631.465797394185;7682.462227644067;7685.385169186452;7687.807804804868;7693.538387696756;37954.81022594897;38062.809685951666;38171;38179.23641527154;38236.808815955745;3557.075160458274;3602.8919132426067;3604.8017359045243;3606.2066345404232;3609.1157666371905;31139;31228.531572026437;31240.062798116094;31269.687303126906;31334.21664458384;4887.608991280689;5050.141475949097;5060.1903695408555;5106.08090543702;5107.7446127693465;187149;189315;189451;189803.05098474558;189809;46924.5307546927;47191;47195.5280447198;47245;47305.290420643716;48893;49348.25977610339;49351.50648493541;49361.259581106315;49403.50596493989;14760.704785904221;15144.394224230908;16518.917405412896;16867.325306987583;16892.40876569314;13672.589822305346;13864.376103075387;13869.237191954539;13930.512432065032;13974.650633734238;14587.854121458862;14676.41294348214;14721.26393680333;14726.779098313535;14755.55733328002;5691.317041954909;5707.543396528268;5770.278715160605;5775.133729940459;5903.6457812530525;996.9750756231152;1002.5488530161457;1008.5915204342253;1009.1724785675675;1025.1337619711333;22526.66210006851;22710.545789084124;22945.54108917812;22957.426064348525;23052.077916883136;1094.3215206571977;1124.5839039555253;1125.8640409766804;1139.4245905817509;1150.3787954504642;12263.632091037283;16108.838911610968;16512.183659797414;16701.582460438585;16936.915315423343;227770;233432;235285;236227;236773;56611.71694141503;57353.71323143441;57436;57740.4225957735;57800.132998005065;1361.5711051018898;1410.597979575816;1469.7207530569158;1471.4187895781106;1480.1489143742392;27544.72455275502;27664.017980567492;27792;27996;28077.859610701817;38074.80962595169;38094.23811523809;38193.23613527769;38281.6171838278;38308.42537361886;215797;215932;217473;217738;217893;34097.48853767245;34909.47635785516;37074.443883341235;37622.81188594149;37728.056798580255;47300;47599.52400475951;47945.520544794104;48077.278840818115;48270;34101.147471313416;34246.657533424346;34312.485312720826;34715.826420867736;34991.30017399587;33163;33806.661933380354;34235.82882085673;34310.82844585761;34734.65265347415;36099.458508121876;37214.255714886094;37289.440658389605;37661.623383766906;37747;32906;33004.66995330016;33227.50158747572;33320.666793332726;33808.830955845064;23855.165069222494;24785.62821557714;24832.255032349058;25098.498030038932;25669.844856981108 05:23:12 INFO - PID 9319 | |2;jquery.html;570.6718636783847;575.1028395702707;577.7688924430229;578.7308901360866;593.2347272019095;21852.89073554632;22638.207662731827;22640.20759273426;22720.54558908823;22782;24603.523788572697;27301.590476142876;27363.315917102034;27449.72550274497;27549.933754968406;6526.934730652692;6711.4295284900845;6713.966430167867;6714.462842972551;6716.395524402803;6539.247986481544;6637.767678131267;6639.767608133716;6641.66791660415;6641.667916604175;3984.820683069269;3988.022934381066;5031.2453132030305;6347.777827776029;6349.36506349366;6265.154204182422;6307.148534947769;6309.873802523924;6310;6313.684315784192;5890.352061273256;5902.2622172228475;5910.527157827363;5911.054231322968;5916.763329466816;28165.57751633727;29753.70246297531;30021.69978300211;30273.940412085693;30292.54561181584;1849.9075046247633;1931.5750534882443;1931.6619591571418;1932.053293886;1934.4680212941414;18610.906945465187;18729.438116856516;19142.617147657107;19203.903980480147;19799.505012374662;2358.8820558971984;2424.2121310574134;2426;2446.498467814094;2499.5375855466796;129794;136988.31505842507;138115.30942345323;146423.53576464206;147432;14476.218626074658;14856.937268266349;14969.476068337774;15073.170975596227;15143.697126057417;24247.878760606083;24251.272461826175;24299.39251518726;24302.392440189138;24353;10250.487475626338;10497.737556560995;10660.946695266473;10720.73198170052;10726.946365268124;9640.469774162488;9673.226141908772;9711.757206069904;9740.188390886111;9765.609375625038;8951.641934322684;9252.583633736498;9258.72223833286;9337.626494940127;9586.185174260267;2614.35948192694;2625.829321094123;2701;2704.4591081783724;2810.5222112240967;967.5500892085073;971.0386717150112;978.574320170728;984.6455276100598;992.6773798515367;19587.314443994394;19727.60544789096;20096.497587560134;20345;20443.897780511;1038.2576457832686;1050.81610718124;1056.4031322302874;1061.4798748613123;1069.8341757027674;11024.430097242226;11324;11352.772944541062;11450.599229027112;11477.540898363972;474552;480273;489014;489054;495542;105900.47049764857;109015.45492272647;109509.45245273723;109832.4508377453;110087.44956275167;1096.2819353323587;1175.61204802415;1220.9694757630953;1236.1532350340046;1249.1256120715354;20091.59816803685;20717;21011.789882101595;21127.521073524706;21288.36134915955;54188.45811541834;54655.72672136614;55176.72411637916;55265;56896;70417.29582704313;70736;70788;71194.28805711877;71203;14479;14743.262836857899;15285.08289502589;15418.768718469457;15443.69112617719;23663.29010129699;23929.16247931314;24349.51300974006;24465;24716.50566988614;18283.451496455127;18714.625707486048;19415.90292048587;19419;19428.90285548563;16831.579210519834;16933.661326773643;17654.20556074954;17676.292948281924;17917.731234031762;19805.20779168817;19889.403317900487;20343.796562034782;20413.59172816565;20421.285255016002;14875.107493550422;15026.774598381251;15153.318100685716;15239.695206096038;15385.076895386313;19040.523986899883;19064.809351906304;19144.712829307293;19146.808531914503;19217.51956201106 05:23:12 INFO - PID 9319 | |3;mootools.html;1336.5255334356302;1401.3903951780976;1405.241169768325;1409.6053105130563;1415.0519152168047;13652.658683532916;13846.584602461942;14304.35630396632;14363.928180359084;14382.49661261856;14315.642108947282;14396.568102956902;14571.708565828678;14574.781378279335;14595.416183352652;2658.667666541682;2692.9461410771773;2697.662792150981;2702.9864850675717;2705.702372739007;2534.7845433138204;2645.695744989322;2756.3935934094466;2762.406082692221;2763.875625596853;2529.4941011797714;2532.9493410131786;2558.3092565007437;2559.7184309725917;2561.462092960471;2573.4853029394103;2598.805089618267;2620.2794231586277;2622.4230669252734;2680.7855371570326;2485.6395822605623;2552.7574880386333;2554.591265397546;2556.297018319958;2560.5647040003246;13915.58253252404;13990.86009139916;14051.297435128203;14085.929570352186;14100.647483812883;7600.239976002411;7868.488548244346;7880.817877318391;7895;7926.286634202949;5106.617003724698;5251.343582052244;5424.484673955968;5451.48210919962;5466.835994920159;1660.2362913060008;1678.1441464852892;1699.2693141948957;1722.896626202432;1736.8002679691872;22626.321210363716;22780.658290125662;22989.88505057481;22995.999999999833;23008.309750707507;6525.543211975211;6985.441164706811;7170.784876453715;7208.171060328024;7272.709091636275;14535.709285814224;15214.315355809014;15226.771598426032;15252.542423727306;15288.235588220767;7519.398448124137;7673.769786906401;7729.343005844454;8290.751277461686;8303.294219991369;7330.376917962033;7339.266073392618;7361.595112268769;7362.263773622595;7385.29839665231;4856.80572777092;4979.502049795064;5019.949800501949;5026.84919452417;5039.462963796077;1428.8928330375263;1463.3853781411833;1473.9263036848115;1478.2756449339747;1482.0588926031833;855.5935930432995;872.4198408058696;886.4902680958346;891.2914233184633;893.7676204186959;7190;7210.206877243499;8256.958715206385;8670.349723770742;8697.782555436164;953.1040821627648;953.756792018031;990.1929927109383;1005.3565717940523;1016.4866742295094;4951.183054795918;5114.718690471986;5365.731713414314;5457.2359869618285;5649.971750141305;19061.332853350064;19131.61736765257;19138.425847224604;19490.610187796166;19514.902425488064;19328.516787080152;19456.416307510797;19740.605187896163;19745.901270493556;19746;9950.154236889946;10404.739881502872;10577.9471102644;10587.258891877495;10609.681709548726;7925.326347260317;8181.427300088937;8394.11861754507;8472.279856212292;8482.279006284534;17054.317827287265;17210.225539850988;17245.051522166403;17498.212580433657;17678.204480798137;17350.958942463494;17372.39196628112;17425.302987880343;17495.650086997935;17520.737188941923;11245.325280483197;11580.536778528765;11589;11591.88408115908;11646.068314534987;12882.420291086735;12889.677758056123;13039;13061.281629510468;13067.215967041628;10281.588736450458;10389.324693895174;10575.629852955417;10586.682399528027;10608.416537090534;10248.692539223835;10259.333143345659;10375.377477351383;10534.894651053391;10657.25399222047;8420.11588783169;8634.352423568133;8635.006974198048;8662.523561204194;8724.476531408136;6478.611283323015;6537.757826013042;6563.310852360624;6567.146270984741;6575.27671956094;6860.5540639858245;6961.721531138698;6964.303569643198;6967.338102880219;6974.197967233833 05:23:12 INFO - PID 9319 | |4;prototype.html;495.96591107540763;501.55612283129443;525.2488940814635;528.0495108804151;529.8860744939838;6160.322364539897;6240.843978900519;6241.469475094623;6279.780207692732;6288.213973253343;5788.363280039202;5963.493103086232;5994.910076348848;6050.910836049509;6102.237220347457;4867.756612169395;4904.36243288372;4926.408830940283;4927.6797008194535;4967.627427942901;4741.795065294637;4943.134951383511;4972.2044472884345;4978.054169707744;4983.451820299764;4829.705889702398;4854.247591623286;4920.70475771453;4924.482929292427;4936.555709986101;4862.124817532843;4898.926516102261;4902.166631672606;4905.141600219965;4952.5542701157065;4368.956310436887;4469.642428605704;4490.3040028795585;4491.820327186909;4509.909801803979;6039.969800151015;6065.757369705207;6067.211262535886;6084.421979911901;6092.482139018189;5614.831555053355;5684.54523638108;5697.6866272355;5732.226149469851;5739.885202295931;6356.919323714979;6454.644994525299;6457.870842583122;6458.806235812933;6520.250171230322;4822.324874517571;4841.394825646794;4872.415310162768;4882.340883980688;4912.926306105412;6820.590764554093;6840.863182736317;6946.2706415826115;6953.617551034691;6965.572057728154;4980.003999200175;4998.6750861193905;5031.874203144877;5053.9928742398215;5057.013882292991;5073.315102461194;5084.3644544431945;5117.513836185556;5120.1039818031695;5140.280360749498;5663.943360566424;5698.7435565399655;5725.456081672234;5737.569682273846;5755.107958266475;6471.835069687501;6487.7080531376205;6506.536029393444;6508.056331831953;6562.212534495891;1621.5216511129245;1639.5573195237457;1710.8887922285012;1713.0663788235443;1739.3129713762992;1205.9336736479402;1234.5061975209808;1239.2812168941991;1247.6319485751726;1255.6358655989843;5666.858328541737;5688.77244910199;5745.540356771448;5746.827595172152;5759.136129580513;6408.359164083555;6596.73613055482;6625.966870165618;6631.535792494576;6714.294999025032;5665.461781130786;5669.355886792784;5716.371199168088;5744.454276843693;5767.682777447197;3547.6452354764833;3566.4472006838982;3576.1417259857967;3595.334863050344;3627.111357717376;6407.423331900151;6417;6507.7071531781185;6525.934740652628;6544;6833.897491537631;6836.521443499007;6857.828554286083;6875.724971001204;6883.552569082994;6430.164078669743;6435.549511534147;6448.580842245239;6468.773592924319;6514.5114116441455;6598.670066496752;6700.296468870698;6735.225449073223;6766.086578312062;6767.969609117344;6666.26671066192;6686.598804071771;6688.665566721742;6688.966555167193;6696.229933557704;6469.897283383014;6495.1556297681;6505.804825855232;6558.737650494118;6560.278369379269;4999.425066117297;5005.6996580205305;5010.148274793367;5014.674046186986;5029.698218106925;6774.288699686659;6780.186377634715;6781.2879647638265;6794.728210871707;6806.489513286424;6528.183977002875;6544.411003009752;6564.409203171737;6564.442022427956;6583.181754582427;6396.712147953446;6416.743330266924;6437.7103030362805;6490.772822951174;6542.640154791342;6041.399029257338;6195.96902015487;6310.558260921875;6338.65137417428;6380.3619638035825;6387.776427825004;6423.747369262949;6426.5540253442605;6447.774327898501;6514;6303.49572034227;6315.179026726496;6329.145565348619;6349.856820873697;6352.872942541216 05:23:12 INFO - PID 9319 | |5;yui.html;886.986695199572;901.8557030875062;908;920.4569304110576;924.2005665099691;18609;18932.905335473322;19999.800001999993;20037.09833057514;20134.69797953032;19789;19887.403377898685;19888.20447182111;19905;19925.103370348334;966.6858271061916;1013.3160116921085;1014.6550172941198;1018.6231094495047;1019.6737044145876;1023.6519583341662;1024.8001639680228;1025.446259020132;1025.8820235672881;1026.7484466305766;1012.6810054832741;1051.805415998039;1055.8205105132108;1064.0529928363762;1067.882532921378;1041.9479026048705;1042.10899680773;1042.1298215989657;1043.1341986151476;1044.2742294105599;766.8274638206415;767.4436033875452;770.010536460651;772.1969152081829;772.7411317208741;19302.32441864528;19458.610827783366;20043.699344509845;20265.797342026537;20340.79659203404;21646.89176554123;21800.672989905164;22334.664980025314;22345.10619575215;22398.32805015852;16412.75380869288;16673.166341682987;16825;16834.07412592307;16901.57746056346;3642.981785091084;3672.797996110213;3677.4667673187237;3678.4850120983083;3681.6134305897763;127849.36075319657;128488.35755821255;128855.35572322173;131800.34099829438;131944;16420.75368869468;16540.503784886474;16904.239309231114;16933.222011688842;17059.829401706073;29704.2573935649;30382.54426183609;30461.543076853864;30815.22961925925;31116.22209444782;7815.29662330393;8208.056073551617;8523.914760852313;8634.913650863411;8644.438111522732;4166.708330416903;4407.757573333497;4415.602595766396;4423.248047831877;4427.402300689366;5246.7638956247065;5290.68255904647;5344.545713614309;5390.299261096033;5399.892002159935;1104.1939384249542;1111.8109921313396;1129.0290350298737;1130.5251794246435;1130.8529891114267;592.5407808948099;676.7834293026283;691.4537515362907;701.1831216632668;708.3943228539628;12172.391380430943;12722.300273484861;12770.23378597287;12974.416151273215;13024.54414095502;673.7911247513287;684.6884667476286;688.421725750362;692.2731132311053;695.8225652458692;5609.80365687199;5614.719264036782;5644.9435505644415;5715.257016587817;5817.592768506164;109186;109394;109398;110580;111366;91827;92339.5383023094;93821;94917;95716;78267.60866195746;79402;81761.59119204484;83871.16128838755;84329.15670843335;12234.265944043385;12519.374031298581;12871.420786064462;12878.098533102968;13090.541831035867;33322.00033998984;33919;34549.65450345464;35233.647663523036;36411.08972275714;42275.78862105793;51576.74211628918;61202.38797612145;62439.37560624517;66247.33752662412;17499.0375529342;17806;17815.287388504832;17899;18020.63958720792;18503.81496185021;18605.627887442446;18686.06569671538;18780.812191877907;18885;18094.457166285032;18398.540036499195;18435.53911152232;18630.44108676742;18717.251309947995;18614.906925465828;18718.53203669919;18841.90579047096;18851.340203092826;18977.14602842903;18324.358647447276;18612.906935465235;18660.720089198403;18720.7191892119;18873.150708218436;9327.292573154524;9454.621815127593;9482.525873706149;9655.08276713711;9693.951530242304;12416.937915310365;12808.743825123633;12817.743645127232;12913.741725165632;12980.610581682564 05:23:12 INFO - PID 9319 | __end_tp_report 05:23:12 INFO - PID 9319 | __start_cc_report 05:23:12 INFO - PID 9319 | _x_x_mozilla_cycle_collect,380 05:23:12 INFO - PID 9319 | __end_cc_report 05:23:12 INFO - PID 9319 | __startTimestamp1502194992184__endTimestamp 05:23:12 INFO - PID 9319 | 05:23:12 INFO - PID 9319 | ------- Summary: start ------- 05:23:12 INFO - PID 9319 | Number of tests: 6 05:23:12 INFO - PID 9319 | 05:23:12 INFO - PID 9319 | [#0] dojo.html Cycles:165 Average:6229.24 Median:6516.87 stddev:737.59 (11.3%) stddev-sans-first:699.92 05:23:12 INFO - PID 9319 | Values: 3177.4 3186.1 3207.6 3213.4 3243.8 6198.3 6207.9 6298.8 6307.0 6371.7 6304.2 6326.5 6389.4 6401.1 6421.9 5772.9 5810.1 5845.7 5894.1 5911.9 5645.5 5725.7 5744.5 5758.4 5820.3 5811.5 5816.0 5818.3 5819.2 5820.5 5751.6 5771.9 5823.6 5846.1 5848.4 5633.1 5676.2 5695.6 5713.8 5763.3 6208.2 6230.3 6247.8 6258.0 6285.7 6748.3 6763.2 6814.5 6822.4 6845.6 6734.2 6753.5 6806.1 6847.6 6855.6 6249.2 6283.5 6284.5 6290.3 6303.9 6895.2 6914.7 6916.9 6917.9 6926.2 6586.7 6604.4 6674.8 6676.2 6676.7 6806.4 6838.0 6842.0 6843.9 6860.0 6633.1 6709.5 6729.2 6804.9 6811.1 6628.1 6749.1 6759.9 6764.5 6815.2 5469.0 5473.0 5474.6 5480.2 5504.9 4639.6 4675.0 4682.0 4699.6 4745.2 5808.0 5816.3 5890.7 5894.2 5907.3 6719.5 6810.6 6817.0 6821.2 6870.3 5841.1 5844.8 5848.0 5848.8 5863.1 5882.3 5950.9 5960.8 5962.2 5986.6 6555.4 6564.2 6567.9 6572.5 6577.9 5690.3 6663.4 6746.8 6830.9 6912.9 6519.2 6555.0 6582.5 6656.5 6677.4 6646.5 6653.9 6656.5 6686.4 6698.6 6812.4 6819.7 6828.8 6830.0 6834.3 6515.9 6576.1 6576.1 6597.6 6631.1 6610.3 6618.6 6665.1 6699.9 6709.5 6460.9 6498.0 6520.6 6613.6 6614.8 6590.9 6621.8 6750.1 6760.8 6766.2 6405.5 6422.0 6425.7 6516.9 6520.1 05:23:12 INFO - PID 9319 | 05:23:12 INFO - PID 9319 | [#1] ext.html Cycles:180 Average:38917.02 Median:3605.50 stddev:55434.73 (1537.5%) stddev-sans-first:55516.24 05:23:12 INFO - PID 9319 | Values: 771.4 829.3 852.9 855.5 1150.5 30331.2 41741.8 42894.4 42974.7 42997.0 42920.0 43054.4 43155.0 43157.1 43205.6 7510.8 7795.3 7797.6 7799.5 7803.1 7598.1 7711.2 7737.2 7737.8 7739.4 7678.7 7741.0 7754.5 7762.5 7762.8 7687.6 7732.7 7739.2 7748.1 7751.3 7631.5 7682.5 7685.4 7687.8 7693.5 37954.8 38062.8 38171.0 38179.2 38236.8 3557.1 3602.9 3604.8 3606.2 3609.1 31139.0 31228.5 31240.1 31269.7 31334.2 4887.6 5050.1 5060.2 5106.1 5107.7 187149.0 189315.0 189451.0 189803.1 189809.0 46924.5 47191.0 47195.5 47245.0 47305.3 48893.0 49348.3 49351.5 49361.3 49403.5 14760.7 15144.4 16518.9 16867.3 16892.4 13672.6 13864.4 13869.2 13930.5 13974.7 14587.9 14676.4 14721.3 14726.8 14755.6 5691.3 5707.5 5770.3 5775.1 5903.6 997.0 1002.5 1008.6 1009.2 1025.1 22526.7 22710.5 22945.5 22957.4 23052.1 1094.3 1124.6 1125.9 1139.4 1150.4 12263.6 16108.8 16512.2 16701.6 16936.9 227770.0 233432.0 235285.0 236227.0 236773.0 56611.7 57353.7 57436.0 57740.4 57800.1 1361.6 1410.6 1469.7 1471.4 1480.1 27544.7 27664.0 27792.0 27996.0 28077.9 38074.8 38094.2 38193.2 38281.6 38308.4 215797.0 215932.0 217473.0 217738.0 217893.0 34097.5 34909.5 37074.4 37622.8 37728.1 47300.0 47599.5 47945.5 48077.3 48270.0 34101.1 34246.7 34312.5 34715.8 34991.3 33163.0 33806.7 34235.8 34310.8 34734.7 36099.5 37214.3 37289.4 37661.6 37747.0 32906.0 33004.7 33227.5 33320.7 33808.8 23855.2 24785.6 24832.3 25098.5 25669.8 05:23:12 INFO - PID 9319 | 05:23:12 INFO - PID 9319 | [#2] jquery.html Cycles:180 Average:34756.43 Median:13011.09 stddev:81740.92 (628.2%) stddev-sans-first:81929.92 05:23:12 INFO - PID 9319 | Values: 570.7 575.1 577.8 578.7 593.2 21852.9 22638.2 22640.2 22720.5 22782.0 24603.5 27301.6 27363.3 27449.7 27549.9 6526.9 6711.4 6714.0 6714.5 6716.4 6539.2 6637.8 6639.8 6641.7 6641.7 3984.8 3988.0 5031.2 6347.8 6349.4 6265.2 6307.1 6309.9 6310.0 6313.7 5890.4 5902.3 5910.5 5911.1 5916.8 28165.6 29753.7 30021.7 30273.9 30292.5 1849.9 1931.6 1931.7 1932.1 1934.5 18610.9 18729.4 19142.6 19203.9 19799.5 2358.9 2424.2 2426.0 2446.5 2499.5 129794.0 136988.3 138115.3 146423.5 147432.0 14476.2 14856.9 14969.5 15073.2 15143.7 24247.9 24251.3 24299.4 24302.4 24353.0 10250.5 10497.7 10660.9 10720.7 10726.9 9640.5 9673.2 9711.8 9740.2 9765.6 8951.6 9252.6 9258.7 9337.6 9586.2 2614.4 2625.8 2701.0 2704.5 2810.5 967.6 971.0 978.6 984.6 992.7 19587.3 19727.6 20096.5 20345.0 20443.9 1038.3 1050.8 1056.4 1061.5 1069.8 11024.4 11324.0 11352.8 11450.6 11477.5 474552.0 480273.0 489014.0 489054.0 495542.0 105900.5 109015.5 109509.5 109832.5 110087.4 1096.3 1175.6 1221.0 1236.2 1249.1 20091.6 20717.0 21011.8 21127.5 21288.4 54188.5 54655.7 55176.7 55265.0 56896.0 70417.3 70736.0 70788.0 71194.3 71203.0 14479.0 14743.3 15285.1 15418.8 15443.7 23663.3 23929.2 24349.5 24465.0 24716.5 18283.5 18714.6 19415.9 19419.0 19428.9 16831.6 16933.7 17654.2 17676.3 17917.7 19805.2 19889.4 20343.8 20413.6 20421.3 14875.1 15026.8 15153.3 15239.7 15385.1 19040.5 19064.8 19144.7 19146.8 19217.5 05:23:12 INFO - PID 9319 | 05:23:12 INFO - PID 9319 | [#3] mootools.html Cycles:180 Average:8716.05 Median:2553.67 stddev:5939.17 (232.6%) stddev-sans-first:5929.95 05:23:12 INFO - PID 9319 | Values: 1336.5 1401.4 1405.2 1409.6 1415.1 13652.7 13846.6 14304.4 14363.9 14382.5 14315.6 14396.6 14571.7 14574.8 14595.4 2658.7 2692.9 2697.7 2703.0 2705.7 2534.8 2645.7 2756.4 2762.4 2763.9 2529.5 2532.9 2558.3 2559.7 2561.5 2573.5 2598.8 2620.3 2622.4 2680.8 2485.6 2552.8 2554.6 2556.3 2560.6 13915.6 13990.9 14051.3 14085.9 14100.6 7600.2 7868.5 7880.8 7895.0 7926.3 5106.6 5251.3 5424.5 5451.5 5466.8 1660.2 1678.1 1699.3 1722.9 1736.8 22626.3 22780.7 22989.9 22996.0 23008.3 6525.5 6985.4 7170.8 7208.2 7272.7 14535.7 15214.3 15226.8 15252.5 15288.2 7519.4 7673.8 7729.3 8290.8 8303.3 7330.4 7339.3 7361.6 7362.3 7385.3 4856.8 4979.5 5019.9 5026.8 5039.5 1428.9 1463.4 1473.9 1478.3 1482.1 855.6 872.4 886.5 891.3 893.8 7190.0 7210.2 8257.0 8670.3 8697.8 953.1 953.8 990.2 1005.4 1016.5 4951.2 5114.7 5365.7 5457.2 5650.0 19061.3 19131.6 19138.4 19490.6 19514.9 19328.5 19456.4 19740.6 19745.9 19746.0 9950.2 10404.7 10577.9 10587.3 10609.7 7925.3 8181.4 8394.1 8472.3 8482.3 17054.3 17210.2 17245.1 17498.2 17678.2 17351.0 17372.4 17425.3 17495.7 17520.7 11245.3 11580.5 11589.0 11591.9 11646.1 12882.4 12889.7 13039.0 13061.3 13067.2 10281.6 10389.3 10575.6 10586.7 10608.4 10248.7 10259.3 10375.4 10534.9 10657.3 8420.1 8634.4 8635.0 8662.5 8724.5 6478.6 6537.8 6563.3 6567.1 6575.3 6860.6 6961.7 6964.3 6967.3 6974.2 05:23:12 INFO - PID 9319 | 05:23:12 INFO - PID 9319 | [#4] prototype.html Cycles:180 Average:5479.69 Median:6058.33 stddev:1537.69 (25.4%) stddev-sans-first:1495.82 05:23:12 INFO - PID 9319 | Values: 496.0 501.6 525.2 528.0 529.9 6160.3 6240.8 6241.5 6279.8 6288.2 5788.4 5963.5 5994.9 6050.9 6102.2 4867.8 4904.4 4926.4 4927.7 4967.6 4741.8 4943.1 4972.2 4978.1 4983.5 4829.7 4854.2 4920.7 4924.5 4936.6 4862.1 4898.9 4902.2 4905.1 4952.6 4369.0 4469.6 4490.3 4491.8 4509.9 6040.0 6065.8 6067.2 6084.4 6092.5 5614.8 5684.5 5697.7 5732.2 5739.9 6356.9 6454.6 6457.9 6458.8 6520.3 4822.3 4841.4 4872.4 4882.3 4912.9 6820.6 6840.9 6946.3 6953.6 6965.6 4980.0 4998.7 5031.9 5054.0 5057.0 5073.3 5084.4 5117.5 5120.1 5140.3 5663.9 5698.7 5725.5 5737.6 5755.1 6471.8 6487.7 6506.5 6508.1 6562.2 1621.5 1639.6 1710.9 1713.1 1739.3 1205.9 1234.5 1239.3 1247.6 1255.6 5666.9 5688.8 5745.5 5746.8 5759.1 6408.4 6596.7 6626.0 6631.5 6714.3 5665.5 5669.4 5716.4 5744.5 5767.7 3547.6 3566.4 3576.1 3595.3 3627.1 6407.4 6417.0 6507.7 6525.9 6544.0 6833.9 6836.5 6857.8 6875.7 6883.6 6430.2 6435.5 6448.6 6468.8 6514.5 6598.7 6700.3 6735.2 6766.1 6768.0 6666.3 6686.6 6688.7 6689.0 6696.2 6469.9 6495.2 6505.8 6558.7 6560.3 4999.4 5005.7 5010.1 5014.7 5029.7 6774.3 6780.2 6781.3 6794.7 6806.5 6528.2 6544.4 6564.4 6564.4 6583.2 6396.7 6416.7 6437.7 6490.8 6542.6 6041.4 6196.0 6310.6 6338.7 6380.4 6387.8 6423.7 6426.6 6447.8 6514.0 6303.5 6315.2 6329.1 6349.9 6352.9 05:23:12 INFO - PID 9319 | 05:23:12 INFO - PID 9319 | [#5] yui.html Cycles:180 Average:22992.36 Median:19896.60 stddev:31473.94 (158.2%) stddev-sans-first:31518.46 05:23:12 INFO - PID 9319 | Values: 887.0 901.9 908.0 920.5 924.2 18609.0 18932.9 19999.8 20037.1 20134.7 19789.0 19887.4 19888.2 19905.0 19925.1 966.7 1013.3 1014.7 1018.6 1019.7 1023.7 1024.8 1025.4 1025.9 1026.7 1012.7 1051.8 1055.8 1064.1 1067.9 1041.9 1042.1 1042.1 1043.1 1044.3 766.8 767.4 770.0 772.2 772.7 19302.3 19458.6 20043.7 20265.8 20340.8 21646.9 21800.7 22334.7 22345.1 22398.3 16412.8 16673.2 16825.0 16834.1 16901.6 3643.0 3672.8 3677.5 3678.5 3681.6 127849.4 128488.4 128855.4 131800.3 131944.0 16420.8 16540.5 16904.2 16933.2 17059.8 29704.3 30382.5 30461.5 30815.2 31116.2 7815.3 8208.1 8523.9 8634.9 8644.4 4166.7 4407.8 4415.6 4423.2 4427.4 5246.8 5290.7 5344.5 5390.3 5399.9 1104.2 1111.8 1129.0 1130.5 1130.9 592.5 676.8 691.5 701.2 708.4 12172.4 12722.3 12770.2 12974.4 13024.5 673.8 684.7 688.4 692.3 695.8 5609.8 5614.7 5644.9 5715.3 5817.6 109186.0 109394.0 109398.0 110580.0 111366.0 91827.0 92339.5 93821.0 94917.0 95716.0 78267.6 79402.0 81761.6 83871.2 84329.2 12234.3 12519.4 12871.4 12878.1 13090.5 33322.0 33919.0 34549.7 35233.6 36411.1 42275.8 51576.7 61202.4 62439.4 66247.3 17499.0 17806.0 17815.3 17899.0 18020.6 18503.8 18605.6 18686.1 18780.8 18885.0 18094.5 18398.5 18435.5 18630.4 18717.3 18614.9 18718.5 18841.9 18851.3 18977.1 18324.4 18612.9 18660.7 18720.7 18873.2 9327.3 9454.6 9482.5 9655.1 9694.0 12416.9 12808.7 12817.7 12913.7 12980.6 05:23:12 INFO - PID 9319 | -------- Summary: end -------- 05:23:12 INFO - PID 9319 | 05:23:12 INFO - TEST-INFO | 9319: exit 0 05:23:12 INFO - TEST-OK | dromaeo_css | took 1113923ms 05:23:12 INFO - TEST-START | kraken 05:23:12 INFO - Initialising browser for kraken test... 05:23:12 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60084/getInfo.html -profile /tmp/tmpR4Z_PW/profile 05:23:12 INFO - TEST-INFO | started process 9689 (/builds/slave/test/build/application/firefox/firefox http://localhost:60084/getInfo.html) 05:23:19 INFO - TEST-INFO | 9689: exit 0 05:23:19 INFO - Browser initialized. 05:23:19 INFO - Running cycle 1/1 for kraken test... 05:23:19 INFO - TEST-INFO | started process 9916 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR4Z_PW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:23:20 INFO - PID 9916 | 05:23:20 INFO - PID 9916 | (/builds/slave/test/build/application/firefox/firefox:9971): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:23:20 INFO - PID 9916 | 05:23:30 INFO - PID 9916 | RSS: Main: 170471424 05:23:30 INFO - PID 9916 | 05:23:33 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60084/tests/kraken/driver.html?testName=audio-beat-detection) 05:23:34 INFO - PID 9916 | RSS: Main: 175775744 05:23:34 INFO - PID 9916 | 05:23:36 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60084/tests/kraken/driver.html?testName=audio-dft) 05:23:37 INFO - PID 9916 | RSS: Main: 176218112 05:23:37 INFO - PID 9916 | 05:23:40 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60084/tests/kraken/driver.html?testName=audio-fft) 05:23:41 INFO - PID 9916 | RSS: Main: 170831872 05:23:41 INFO - PID 9916 | 05:23:43 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60084/tests/kraken/driver.html?testName=audio-oscillator) 05:23:44 INFO - PID 9916 | RSS: Main: 171167744 05:23:44 INFO - PID 9916 | 05:23:46 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60084/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:23:46 INFO - PID 9916 | RSS: Main: 171573248 05:23:46 INFO - PID 9916 | 05:23:50 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60084/tests/kraken/driver.html?testName=imaging-darkroom) 05:23:50 INFO - PID 9916 | RSS: Main: 174120960 05:23:50 INFO - PID 9916 | 05:23:54 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60084/tests/kraken/driver.html?testName=imaging-desaturate) 05:23:54 INFO - PID 9916 | RSS: Main: 173969408 05:23:54 INFO - PID 9916 | 05:23:57 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60084/tests/kraken/driver.html?testName=json-parse-financial) 05:23:58 INFO - PID 9916 | RSS: Main: 174768128 05:23:58 INFO - PID 9916 | 05:24:00 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60084/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:24:00 INFO - PID 9916 | RSS: Main: 174428160 05:24:00 INFO - PID 9916 | 05:24:02 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60084/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:24:03 INFO - PID 9916 | RSS: Main: 174219264 05:24:03 INFO - PID 9916 | 05:24:05 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60084/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:24:06 INFO - PID 9916 | RSS: Main: 174268416 05:24:06 INFO - PID 9916 | 05:24:08 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60084/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:24:09 INFO - PID 9916 | RSS: Main: 174678016 05:24:09 INFO - PID 9916 | 05:24:12 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60084/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:24:13 INFO - PID 9916 | RSS: Main: 174780416 05:24:13 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | Cycle 1(1): loaded http://localhost:60084/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60084/tests/kraken/driver.html?testName=ai-astar) 05:24:15 INFO - PID 9916 | RSS: Main: 176091136 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | __start_tp_report 05:24:15 INFO - PID 9916 | _x_x_mozilla_page_load 05:24:15 INFO - PID 9916 | _x_x_mozilla_page_load_details 05:24:15 INFO - PID 9916 | |i|pagename|runs| 05:24:15 INFO - PID 9916 | |0;ai-astar;98;113;99;117;108;108;115;99;109;107 05:24:15 INFO - PID 9916 | |1;audio-beat-detection;152;123;142;180;128;141;141;122;169;122 05:24:15 INFO - PID 9916 | |2;audio-dft;169;163;162;164;162;165;166;162;161;164 05:24:15 INFO - PID 9916 | |3;audio-fft;75;79;75;176;75;80;78;74;163;79 05:24:15 INFO - PID 9916 | |4;audio-oscillator;84;78;137;83;79;78;125;77;78;127 05:24:15 INFO - PID 9916 | |5;imaging-gaussian-blur;114;125;118;115;114;114;115;114;115;122 05:24:15 INFO - PID 9916 | |6;imaging-darkroom;150;151;154;151;150;152;151;150;150;150 05:24:15 INFO - PID 9916 | |7;imaging-desaturate;98;100;94;94;94;94;98;101;93;97 05:24:15 INFO - PID 9916 | |8;json-parse-financial;55;54;54;60;53;54;57;60;53;59 05:24:15 INFO - PID 9916 | |9;json-stringify-tinderbox;53;53;53;60;53;52;60;52;53;63 05:24:15 INFO - PID 9916 | |10;stanford-crypto-aes;79;86;77;91;83;90;77;88;88;77 05:24:15 INFO - PID 9916 | |11;stanford-crypto-ccm;142;135;138;132;135;138;138;135;127;172 05:24:15 INFO - PID 9916 | |12;stanford-crypto-pbkdf2;178;176;187;176;178;184;175;178;192;184 05:24:15 INFO - PID 9916 | |13;stanford-crypto-sha256-iterative;72;72;65;66;70;65;69;69;75;65 05:24:15 INFO - PID 9916 | __end_tp_report 05:24:15 INFO - PID 9916 | __start_cc_report 05:24:15 INFO - PID 9916 | _x_x_mozilla_cycle_collect,942 05:24:15 INFO - PID 9916 | __end_cc_report 05:24:15 INFO - PID 9916 | __startTimestamp1502195055541__endTimestamp 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | ------- Summary: start ------- 05:24:15 INFO - PID 9916 | Number of tests: 14 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#0] ai-astar Cycles:10 Average:107.30 Median:116.00 stddev:6.78 (5.8%) stddev-sans-first:6.30 05:24:15 INFO - PID 9916 | Values: 98.0 113.0 99.0 117.0 108.0 108.0 115.0 99.0 109.0 107.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:141.50 stddev:20.09 (14.2%) stddev-sans-first:20.98 05:24:15 INFO - PID 9916 | Values: 152.0 123.0 142.0 180.0 128.0 141.0 141.0 122.0 169.0 122.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#2] audio-dft Cycles:10 Average:163.80 Median:164.00 stddev:2.39 (1.5%) stddev-sans-first:1.64 05:24:15 INFO - PID 9916 | Values: 169.0 163.0 162.0 164.0 162.0 165.0 166.0 162.0 161.0 164.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#3] audio-fft Cycles:10 Average:95.40 Median:76.50 stddev:39.23 (51.3%) stddev-sans-first:40.91 05:24:15 INFO - PID 9916 | Values: 75.0 79.0 75.0 176.0 75.0 80.0 78.0 74.0 163.0 79.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#4] audio-oscillator Cycles:10 Average:94.60 Median:78.00 stddev:24.49 (31.4%) stddev-sans-first:25.67 05:24:15 INFO - PID 9916 | Values: 84.0 78.0 137.0 83.0 79.0 78.0 125.0 77.0 78.0 127.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:115.00 stddev:3.89 (3.4%) stddev-sans-first:4.01 05:24:15 INFO - PID 9916 | Values: 114.0 125.0 118.0 115.0 114.0 114.0 115.0 114.0 115.0 122.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:1.29 (0.9%) stddev-sans-first:1.32 05:24:15 INFO - PID 9916 | Values: 150.0 151.0 154.0 151.0 150.0 152.0 151.0 150.0 150.0 150.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:94.00 stddev:2.87 (3.1%) stddev-sans-first:2.98 05:24:15 INFO - PID 9916 | Values: 98.0 100.0 94.0 94.0 94.0 94.0 98.0 101.0 93.0 97.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:2.85 (5.1%) stddev-sans-first:3.00 05:24:15 INFO - PID 9916 | Values: 55.0 54.0 54.0 60.0 53.0 54.0 57.0 60.0 53.0 59.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#9] json-stringify-tinderbox Cycles:10 Average:55.20 Median:53.00 stddev:4.10 (7.7%) stddev-sans-first:4.28 05:24:15 INFO - PID 9916 | Values: 53.0 53.0 53.0 60.0 53.0 52.0 60.0 52.0 53.0 63.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:87.00 stddev:5.70 (6.6%) stddev-sans-first:5.80 05:24:15 INFO - PID 9916 | Values: 79.0 86.0 77.0 91.0 83.0 90.0 77.0 88.0 88.0 77.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#11] stanford-crypto-ccm Cycles:10 Average:139.20 Median:138.00 stddev:12.21 (8.8%) stddev-sans-first:12.91 05:24:15 INFO - PID 9916 | Values: 142.0 135.0 138.0 132.0 135.0 138.0 138.0 135.0 127.0 172.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:181.00 stddev:5.65 (3.1%) stddev-sans-first:5.90 05:24:15 INFO - PID 9916 | Values: 178.0 176.0 187.0 176.0 178.0 184.0 175.0 178.0 192.0 184.0 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.50 stddev:3.52 (5.1%) stddev-sans-first:3.54 05:24:15 INFO - PID 9916 | Values: 72.0 72.0 65.0 66.0 70.0 65.0 69.0 69.0 75.0 65.0 05:24:15 INFO - PID 9916 | -------- Summary: end -------- 05:24:15 INFO - PID 9916 | 05:24:15 INFO - PID 9916 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:24:16 INFO - TEST-INFO | 9916: exit 0 05:24:16 INFO - TEST-OK | kraken | took 63466ms 05:24:16 INFO - SUITE-END | took 1177s 05:24:16 INFO - Completed test suite (00:19:37) 05:24:16 INFO - javascript benchmark 05:24:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8509.879628226065, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3177.3645270945817, 3186.060112266881, 3207.647158812531, 3213.3894560033586, 3243.8215898125595, 6198.25620925489, 6207.875842483148, 6298.779542716006, 6307.0, 6371.713272902719, 6304.243490781101, 6326.462250708695, 6389.393007664276, 6401.071844582532, 6421.903671444932, 5772.913406298909, 5810.1284807279035, 5845.707714614274, 5894.056950887837, 5911.9113213301835, 5645.54835613152, 5725.685087320196, 5744.4829965303115, 5758.395368486312, 5820.330661973885, 5811.476967072963, 5815.970920145414, 5818.3308919474375, 5819.243498345209, 5820.476157145856, 5751.597388182829, 5771.855703607402, 5823.592348535604, 5846.093855452412, 5848.444397782204, 5633.070543360341, 5676.205331253628, 5695.629784064023, 5713.8285851424525, 5763.308402991626, 6208.2239720034995, 6230.2523697156175, 6247.843803904893, 6258.030005349178, 6285.717142728588, 6748.29142939994, 6763.188417389895, 6814.522983391164, 6822.4200942919415, 6845.5550389224545, 6734.191896972346, 6753.45972322213, 6806.115205023365, 6847.554908930903, 6855.622940738307, 6249.218847644044, 6283.528735344913, 6284.497240220817, 6290.276618188876, 6303.873922521524, 6895.241523432418, 6914.723411063501, 6916.861662766716, 6917.89623155653, 6926.2034865990045, 6586.7035983380865, 6604.3725846044545, 6674.83312917181, 6676.232233293136, 6676.699548520328, 6806.42145417645, 6838.0, 6842.0, 6843.863122737517, 6859.965700171567, 6633.104530888365, 6709.5303328767495, 6729.22613899398, 6804.9319506804295, 6811.080504131879, 6628.138342015507, 6749.0888730020815, 6759.932400675931, 6764.4926630502905, 6815.2162501312, 5469.042917489423, 5473.0, 5474.562035037187, 5480.150576660544, 5504.889902202013, 4639.582437580634, 4674.97662511692, 4681.95318046822, 4699.577038066591, 4745.169595320874, 5807.970960145172, 5816.272965879265, 5890.705464726832, 5894.17481552583, 5907.2911250650195, 6719.529632925746, 6810.557313774589, 6816.965915170492, 6821.1814582250445, 6870.31296870319, 5841.06542953134, 5844.766209351664, 5848.035074212792, 5848.824535263949, 5863.091220860773, 5882.323532793784, 5950.880982380415, 5960.791372301948, 5962.19510366095, 5986.640801551921, 6555.3772391622715, 6564.245111812107, 6567.901481477782, 6572.474202063823, 6577.86844263112, 5690.3456102548735, 6663.433608143265, 6746.763863264762, 6830.863382732218, 6912.930870691229, 6519.185101862267, 6554.967225163844, 6582.473402127723, 6656.500762442933, 6677.3656502632175, 6646.5347425681675, 6653.933460665331, 6656.500762442933, 6686.431653309329, 6698.5980841149685, 6812.352826481477, 6819.693113809792, 6828.795136145924, 6830.043793868763, 6834.316568343126, 6515.869682606416, 6576.079348891063, 6576.112224849775, 6597.637129957899, 6631.104800851823, 6610.305917878553, 6618.569792963442, 6665.066890635412, 6699.933000670126, 6709.530332876652, 6460.870782584416, 6497.967510162419, 6520.576162549609, 6613.570117942319, 6614.834629134118, 6590.868182636224, 6621.801345959629, 6750.05499230118, 6760.797176084725, 6766.18805743314, 6405.455536279468, 6422.0, 6425.710843011982, 6516.869662606816, 6520.119783829123], "value": 6174.241631809365, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.3906014248744, 829.2619568583959, 852.8891244138262, 855.5166331022975, 1150.4707834396177, 30331.241718957037, 41741.79129104358, 42894.35658465126, 42974.71075867721, 42997.0, 42920.0, 43054.354184687254, 43155.0, 43157.136857262834, 43205.567944320544, 7510.849783004337, 7795.298423141916, 7797.649105790253, 7799.532028078305, 7803.063632364124, 7598.126215485235, 7711.2288771123, 7737.187595302496, 7737.845243095135, 7739.419543534229, 7678.692852285902, 7741.032370953631, 7754.495957762728, 7762.495437796525, 7762.805929851771, 7687.615619219045, 7732.652030658605, 7739.226077392215, 7748.108967468757, 7751.263629955145, 7631.465797394185, 7682.462227644067, 7685.385169186452, 7687.807804804868, 7693.538387696756, 37954.81022594897, 38062.809685951666, 38171.0, 38179.23641527154, 38236.808815955745, 3557.075160458274, 3602.8919132426067, 3604.8017359045243, 3606.2066345404232, 3609.1157666371905, 31139.0, 31228.531572026437, 31240.062798116094, 31269.687303126906, 31334.21664458384, 4887.608991280689, 5050.141475949097, 5060.1903695408555, 5106.08090543702, 5107.7446127693465, 187149.0, 189315.0, 189451.0, 189803.05098474558, 189809.0, 46924.5307546927, 47191.0, 47195.5280447198, 47245.0, 47305.290420643716, 48893.0, 49348.25977610339, 49351.50648493541, 49361.259581106315, 49403.50596493989, 14760.704785904221, 15144.394224230908, 16518.917405412896, 16867.325306987583, 16892.40876569314, 13672.589822305346, 13864.376103075387, 13869.237191954539, 13930.512432065032, 13974.650633734238, 14587.854121458862, 14676.41294348214, 14721.26393680333, 14726.779098313535, 14755.55733328002, 5691.317041954909, 5707.543396528268, 5770.278715160605, 5775.133729940459, 5903.6457812530525, 996.9750756231152, 1002.5488530161457, 1008.5915204342253, 1009.1724785675675, 1025.1337619711333, 22526.66210006851, 22710.545789084124, 22945.54108917812, 22957.426064348525, 23052.077916883136, 1094.3215206571977, 1124.5839039555253, 1125.8640409766804, 1139.4245905817509, 1150.3787954504642, 12263.632091037283, 16108.838911610968, 16512.183659797414, 16701.582460438585, 16936.915315423343, 227770.0, 233432.0, 235285.0, 236227.0, 236773.0, 56611.71694141503, 57353.71323143441, 57436.0, 57740.4225957735, 57800.132998005065, 1361.5711051018898, 1410.597979575816, 1469.7207530569158, 1471.4187895781106, 1480.1489143742392, 27544.72455275502, 27664.017980567492, 27792.0, 27996.0, 28077.859610701817, 38074.80962595169, 38094.23811523809, 38193.23613527769, 38281.6171838278, 38308.42537361886, 215797.0, 215932.0, 217473.0, 217738.0, 217893.0, 34097.48853767245, 34909.47635785516, 37074.443883341235, 37622.81188594149, 37728.056798580255, 47300.0, 47599.52400475951, 47945.520544794104, 48077.278840818115, 48270.0, 34101.147471313416, 34246.657533424346, 34312.485312720826, 34715.826420867736, 34991.30017399587, 33163.0, 33806.661933380354, 34235.82882085673, 34310.82844585761, 34734.65265347415, 36099.458508121876, 37214.255714886094, 37289.440658389605, 37661.623383766906, 37747.0, 32906.0, 33004.66995330016, 33227.50158747572, 33320.666793332726, 33808.830955845064, 23855.165069222494, 24785.62821557714, 24832.255032349058, 25098.498030038932, 25669.844856981108], "value": 17710.50728865374, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.6718636783847, 575.1028395702707, 577.7688924430229, 578.7308901360866, 593.2347272019095, 21852.89073554632, 22638.207662731827, 22640.20759273426, 22720.54558908823, 22782.0, 24603.523788572697, 27301.590476142876, 27363.315917102034, 27449.72550274497, 27549.933754968406, 6526.934730652692, 6711.4295284900845, 6713.966430167867, 6714.462842972551, 6716.395524402803, 6539.247986481544, 6637.767678131267, 6639.767608133716, 6641.66791660415, 6641.667916604175, 3984.820683069269, 3988.022934381066, 5031.2453132030305, 6347.777827776029, 6349.36506349366, 6265.154204182422, 6307.148534947769, 6309.873802523924, 6310.0, 6313.684315784192, 5890.352061273256, 5902.2622172228475, 5910.527157827363, 5911.054231322968, 5916.763329466816, 28165.57751633727, 29753.70246297531, 30021.69978300211, 30273.940412085693, 30292.54561181584, 1849.9075046247633, 1931.5750534882443, 1931.6619591571418, 1932.053293886, 1934.4680212941414, 18610.906945465187, 18729.438116856516, 19142.617147657107, 19203.903980480147, 19799.505012374662, 2358.8820558971984, 2424.2121310574134, 2426.0, 2446.498467814094, 2499.5375855466796, 129794.0, 136988.31505842507, 138115.30942345323, 146423.53576464206, 147432.0, 14476.218626074658, 14856.937268266349, 14969.476068337774, 15073.170975596227, 15143.697126057417, 24247.878760606083, 24251.272461826175, 24299.39251518726, 24302.392440189138, 24353.0, 10250.487475626338, 10497.737556560995, 10660.946695266473, 10720.73198170052, 10726.946365268124, 9640.469774162488, 9673.226141908772, 9711.757206069904, 9740.188390886111, 9765.609375625038, 8951.641934322684, 9252.583633736498, 9258.72223833286, 9337.626494940127, 9586.185174260267, 2614.35948192694, 2625.829321094123, 2701.0, 2704.4591081783724, 2810.5222112240967, 967.5500892085073, 971.0386717150112, 978.574320170728, 984.6455276100598, 992.6773798515367, 19587.314443994394, 19727.60544789096, 20096.497587560134, 20345.0, 20443.897780511, 1038.2576457832686, 1050.81610718124, 1056.4031322302874, 1061.4798748613123, 1069.8341757027674, 11024.430097242226, 11324.0, 11352.772944541062, 11450.599229027112, 11477.540898363972, 474552.0, 480273.0, 489014.0, 489054.0, 495542.0, 105900.47049764857, 109015.45492272647, 109509.45245273723, 109832.4508377453, 110087.44956275167, 1096.2819353323587, 1175.61204802415, 1220.9694757630953, 1236.1532350340046, 1249.1256120715354, 20091.59816803685, 20717.0, 21011.789882101595, 21127.521073524706, 21288.36134915955, 54188.45811541834, 54655.72672136614, 55176.72411637916, 55265.0, 56896.0, 70417.29582704313, 70736.0, 70788.0, 71194.28805711877, 71203.0, 14479.0, 14743.262836857899, 15285.08289502589, 15418.768718469457, 15443.69112617719, 23663.29010129699, 23929.16247931314, 24349.51300974006, 24465.0, 24716.50566988614, 18283.451496455127, 18714.625707486048, 19415.90292048587, 19419.0, 19428.90285548563, 16831.579210519834, 16933.661326773643, 17654.20556074954, 17676.292948281924, 17917.731234031762, 19805.20779168817, 19889.403317900487, 20343.796562034782, 20413.59172816565, 20421.285255016002, 14875.107493550422, 15026.774598381251, 15153.318100685716, 15239.695206096038, 15385.076895386313, 19040.523986899883, 19064.809351906304, 19144.712829307293, 19146.808531914503, 19217.51956201106], "value": 12069.170787330042, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1336.5255334356302, 1401.3903951780976, 1405.241169768325, 1409.6053105130563, 1415.0519152168047, 13652.658683532916, 13846.584602461942, 14304.35630396632, 14363.928180359084, 14382.49661261856, 14315.642108947282, 14396.568102956902, 14571.708565828678, 14574.781378279335, 14595.416183352652, 2658.667666541682, 2692.9461410771773, 2697.662792150981, 2702.9864850675717, 2705.702372739007, 2534.7845433138204, 2645.695744989322, 2756.3935934094466, 2762.406082692221, 2763.875625596853, 2529.4941011797714, 2532.9493410131786, 2558.3092565007437, 2559.7184309725917, 2561.462092960471, 2573.4853029394103, 2598.805089618267, 2620.2794231586277, 2622.4230669252734, 2680.7855371570326, 2485.6395822605623, 2552.7574880386333, 2554.591265397546, 2556.297018319958, 2560.5647040003246, 13915.58253252404, 13990.86009139916, 14051.297435128203, 14085.929570352186, 14100.647483812883, 7600.239976002411, 7868.488548244346, 7880.817877318391, 7895.0, 7926.286634202949, 5106.617003724698, 5251.343582052244, 5424.484673955968, 5451.48210919962, 5466.835994920159, 1660.2362913060008, 1678.1441464852892, 1699.2693141948957, 1722.896626202432, 1736.8002679691872, 22626.321210363716, 22780.658290125662, 22989.88505057481, 22995.999999999833, 23008.309750707507, 6525.543211975211, 6985.441164706811, 7170.784876453715, 7208.171060328024, 7272.709091636275, 14535.709285814224, 15214.315355809014, 15226.771598426032, 15252.542423727306, 15288.235588220767, 7519.398448124137, 7673.769786906401, 7729.343005844454, 8290.751277461686, 8303.294219991369, 7330.376917962033, 7339.266073392618, 7361.595112268769, 7362.263773622595, 7385.29839665231, 4856.80572777092, 4979.502049795064, 5019.949800501949, 5026.84919452417, 5039.462963796077, 1428.8928330375263, 1463.3853781411833, 1473.9263036848115, 1478.2756449339747, 1482.0588926031833, 855.5935930432995, 872.4198408058696, 886.4902680958346, 891.2914233184633, 893.7676204186959, 7190.0, 7210.206877243499, 8256.958715206385, 8670.349723770742, 8697.782555436164, 953.1040821627648, 953.756792018031, 990.1929927109383, 1005.3565717940523, 1016.4866742295094, 4951.183054795918, 5114.718690471986, 5365.731713414314, 5457.2359869618285, 5649.971750141305, 19061.332853350064, 19131.61736765257, 19138.425847224604, 19490.610187796166, 19514.902425488064, 19328.516787080152, 19456.416307510797, 19740.605187896163, 19745.901270493556, 19746.0, 9950.154236889946, 10404.739881502872, 10577.9471102644, 10587.258891877495, 10609.681709548726, 7925.326347260317, 8181.427300088937, 8394.11861754507, 8472.279856212292, 8482.279006284534, 17054.317827287265, 17210.225539850988, 17245.051522166403, 17498.212580433657, 17678.204480798137, 17350.958942463494, 17372.39196628112, 17425.302987880343, 17495.650086997935, 17520.737188941923, 11245.325280483197, 11580.536778528765, 11589.0, 11591.88408115908, 11646.068314534987, 12882.420291086735, 12889.677758056123, 13039.0, 13061.281629510468, 13067.215967041628, 10281.588736450458, 10389.324693895174, 10575.629852955417, 10586.682399528027, 10608.416537090534, 10248.692539223835, 10259.333143345659, 10375.377477351383, 10534.894651053391, 10657.25399222047, 8420.11588783169, 8634.352423568133, 8635.006974198048, 8662.523561204194, 8724.476531408136, 6478.611283323015, 6537.757826013042, 6563.310852360624, 6567.146270984741, 6575.27671956094, 6860.5540639858245, 6961.721531138698, 6964.303569643198, 6967.338102880219, 6974.197967233833], "value": 6360.171880723842, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.96591107540763, 501.55612283129443, 525.2488940814635, 528.0495108804151, 529.8860744939838, 6160.322364539897, 6240.843978900519, 6241.469475094623, 6279.780207692732, 6288.213973253343, 5788.363280039202, 5963.493103086232, 5994.910076348848, 6050.910836049509, 6102.237220347457, 4867.756612169395, 4904.36243288372, 4926.408830940283, 4927.6797008194535, 4967.627427942901, 4741.795065294637, 4943.134951383511, 4972.2044472884345, 4978.054169707744, 4983.451820299764, 4829.705889702398, 4854.247591623286, 4920.70475771453, 4924.482929292427, 4936.555709986101, 4862.124817532843, 4898.926516102261, 4902.166631672606, 4905.141600219965, 4952.5542701157065, 4368.956310436887, 4469.642428605704, 4490.3040028795585, 4491.820327186909, 4509.909801803979, 6039.969800151015, 6065.757369705207, 6067.211262535886, 6084.421979911901, 6092.482139018189, 5614.831555053355, 5684.54523638108, 5697.6866272355, 5732.226149469851, 5739.885202295931, 6356.919323714979, 6454.644994525299, 6457.870842583122, 6458.806235812933, 6520.250171230322, 4822.324874517571, 4841.394825646794, 4872.415310162768, 4882.340883980688, 4912.926306105412, 6820.590764554093, 6840.863182736317, 6946.2706415826115, 6953.617551034691, 6965.572057728154, 4980.003999200175, 4998.6750861193905, 5031.874203144877, 5053.9928742398215, 5057.013882292991, 5073.315102461194, 5084.3644544431945, 5117.513836185556, 5120.1039818031695, 5140.280360749498, 5663.943360566424, 5698.7435565399655, 5725.456081672234, 5737.569682273846, 5755.107958266475, 6471.835069687501, 6487.7080531376205, 6506.536029393444, 6508.056331831953, 6562.212534495891, 1621.5216511129245, 1639.5573195237457, 1710.8887922285012, 1713.0663788235443, 1739.3129713762992, 1205.9336736479402, 1234.5061975209808, 1239.2812168941991, 1247.6319485751726, 1255.6358655989843, 5666.858328541737, 5688.77244910199, 5745.540356771448, 5746.827595172152, 5759.136129580513, 6408.359164083555, 6596.73613055482, 6625.966870165618, 6631.535792494576, 6714.294999025032, 5665.461781130786, 5669.355886792784, 5716.371199168088, 5744.454276843693, 5767.682777447197, 3547.6452354764833, 3566.4472006838982, 3576.1417259857967, 3595.334863050344, 3627.111357717376, 6407.423331900151, 6417.0, 6507.7071531781185, 6525.934740652628, 6544.0, 6833.897491537631, 6836.521443499007, 6857.828554286083, 6875.724971001204, 6883.552569082994, 6430.164078669743, 6435.549511534147, 6448.580842245239, 6468.773592924319, 6514.5114116441455, 6598.670066496752, 6700.296468870698, 6735.225449073223, 6766.086578312062, 6767.969609117344, 6666.26671066192, 6686.598804071771, 6688.665566721742, 6688.966555167193, 6696.229933557704, 6469.897283383014, 6495.1556297681, 6505.804825855232, 6558.737650494118, 6560.278369379269, 4999.425066117297, 5005.6996580205305, 5010.148274793367, 5014.674046186986, 5029.698218106925, 6774.288699686659, 6780.186377634715, 6781.2879647638265, 6794.728210871707, 6806.489513286424, 6528.183977002875, 6544.411003009752, 6564.409203171737, 6564.442022427956, 6583.181754582427, 6396.712147953446, 6416.743330266924, 6437.7103030362805, 6490.772822951174, 6542.640154791342, 6041.399029257338, 6195.96902015487, 6310.558260921875, 6338.65137417428, 6380.3619638035825, 6387.776427825004, 6423.747369262949, 6426.5540253442605, 6447.774327898501, 6514.0, 6303.49572034227, 6315.179026726496, 6329.145565348619, 6349.856820873697, 6352.872942541216], "value": 5027.836488824045, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [886.986695199572, 901.8557030875062, 908.0, 920.4569304110576, 924.2005665099691, 18609.0, 18932.905335473322, 19999.800001999993, 20037.09833057514, 20134.69797953032, 19789.0, 19887.403377898685, 19888.20447182111, 19905.0, 19925.103370348334, 966.6858271061916, 1013.3160116921085, 1014.6550172941198, 1018.6231094495047, 1019.6737044145876, 1023.6519583341662, 1024.8001639680228, 1025.446259020132, 1025.8820235672881, 1026.7484466305766, 1012.6810054832741, 1051.805415998039, 1055.8205105132108, 1064.0529928363762, 1067.882532921378, 1041.9479026048705, 1042.10899680773, 1042.1298215989657, 1043.1341986151476, 1044.2742294105599, 766.8274638206415, 767.4436033875452, 770.010536460651, 772.1969152081829, 772.7411317208741, 19302.32441864528, 19458.610827783366, 20043.699344509845, 20265.797342026537, 20340.79659203404, 21646.89176554123, 21800.672989905164, 22334.664980025314, 22345.10619575215, 22398.32805015852, 16412.75380869288, 16673.166341682987, 16825.0, 16834.07412592307, 16901.57746056346, 3642.981785091084, 3672.797996110213, 3677.4667673187237, 3678.4850120983083, 3681.6134305897763, 127849.36075319657, 128488.35755821255, 128855.35572322173, 131800.34099829438, 131944.0, 16420.75368869468, 16540.503784886474, 16904.239309231114, 16933.222011688842, 17059.829401706073, 29704.2573935649, 30382.54426183609, 30461.543076853864, 30815.22961925925, 31116.22209444782, 7815.29662330393, 8208.056073551617, 8523.914760852313, 8634.913650863411, 8644.438111522732, 4166.708330416903, 4407.757573333497, 4415.602595766396, 4423.248047831877, 4427.402300689366, 5246.7638956247065, 5290.68255904647, 5344.545713614309, 5390.299261096033, 5399.892002159935, 1104.1939384249542, 1111.8109921313396, 1129.0290350298737, 1130.5251794246435, 1130.8529891114267, 592.5407808948099, 676.7834293026283, 691.4537515362907, 701.1831216632668, 708.3943228539628, 12172.391380430943, 12722.300273484861, 12770.23378597287, 12974.416151273215, 13024.54414095502, 673.7911247513287, 684.6884667476286, 688.421725750362, 692.2731132311053, 695.8225652458692, 5609.80365687199, 5614.719264036782, 5644.9435505644415, 5715.257016587817, 5817.592768506164, 109186.0, 109394.0, 109398.0, 110580.0, 111366.0, 91827.0, 92339.5383023094, 93821.0, 94917.0, 95716.0, 78267.60866195746, 79402.0, 81761.59119204484, 83871.16128838755, 84329.15670843335, 12234.265944043385, 12519.374031298581, 12871.420786064462, 12878.098533102968, 13090.541831035867, 33322.00033998984, 33919.0, 34549.65450345464, 35233.647663523036, 36411.08972275714, 42275.78862105793, 51576.74211628918, 61202.38797612145, 62439.37560624517, 66247.33752662412, 17499.0375529342, 17806.0, 17815.287388504832, 17899.0, 18020.63958720792, 18503.81496185021, 18605.627887442446, 18686.06569671538, 18780.812191877907, 18885.0, 18094.457166285032, 18398.540036499195, 18435.53911152232, 18630.44108676742, 18717.251309947995, 18614.906925465828, 18718.53203669919, 18841.90579047096, 18851.340203092826, 18977.14602842903, 18324.358647447276, 18612.906935465235, 18660.720089198403, 18720.7191892119, 18873.150708218436, 9327.292573154524, 9454.621815127593, 9482.525873706149, 9655.08276713711, 9693.951530242304, 12416.937915310365, 12808.743825123633, 12817.743645127232, 12913.741725165632, 12980.610581682564], "value": 8998.515942499651, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 113.0, 99.0, 117.0, 108.0, 108.0, 115.0, 99.0, 109.0, 107.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 123.0, 142.0, 180.0, 128.0, 141.0, 141.0, 122.0, 169.0, 122.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 163.0, 162.0, 164.0, 162.0, 165.0, 166.0, 162.0, 161.0, 164.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 75.0, 176.0, 75.0, 80.0, 78.0, 74.0, 163.0, 79.0], "value": 95.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 78.0, 137.0, 83.0, 79.0, 78.0, 125.0, 77.0, 78.0, 127.0], "value": 94.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 125.0, 118.0, 115.0, 114.0, 114.0, 115.0, 114.0, 115.0, 122.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 154.0, 151.0, 150.0, 152.0, 151.0, 150.0, 150.0, 150.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 100.0, 94.0, 94.0, 94.0, 94.0, 98.0, 101.0, 93.0, 97.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 60.0, 53.0, 54.0, 57.0, 60.0, 53.0, 59.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 60.0, 53.0, 52.0, 60.0, 52.0, 53.0, 63.0], "value": 55.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 86.0, 77.0, 91.0, 83.0, 90.0, 77.0, 88.0, 88.0, 77.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 135.0, 138.0, 132.0, 135.0, 138.0, 138.0, 135.0, 127.0, 172.0], "value": 139.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 176.0, 187.0, 176.0, 178.0, 184.0, 175.0, 178.0, 192.0, 184.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 65.0, 66.0, 70.0, 65.0, 69.0, 69.0, 75.0, 65.0], "value": 68.8, "unit": "score"}]}]} 05:24:16 INFO - Return code: 0 05:24:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:24:16 INFO - # TBPL SUCCESS # 05:24:16 INFO - Running post-action listener: _package_coverage_data 05:24:16 INFO - Running post-action listener: _resource_record_post_action 05:24:16 INFO - [mozharness: 2017-08-08 12:24:16.837877Z] Finished run-tests step (success) 05:24:16 INFO - Running post-run listener: _resource_record_post_run 05:24:16 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 430768128; Read time: 32; Write time: 421600 05:24:16 INFO - TinderboxPrint: CPU usage
13.2% 05:24:16 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 32 05:24:16 INFO - TinderboxPrint: I/O write bytes / time
430,768,128 / 421,600 05:24:16 INFO - TinderboxPrint: CPU idle
8,277.3 (86.8%) 05:24:16 INFO - TinderboxPrint: CPU user
1,204.9 (12.6%) 05:24:16 INFO - TinderboxPrint: Swap in / out
0 / 0 05:24:16 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 108765184; Read time: 0; Write time: 143588 05:24:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:24:16 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 322002944; Read time: 0; Write time: 278012 05:24:17 INFO - Running post-run listener: _upload_blobber_files 05:24:17 INFO - Blob upload gear active. 05:24:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:24:17 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:24:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:24:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:24:17 INFO - (blobuploader) - INFO - Open directory for files ... 05:24:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:24:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:24:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:18 INFO - (blobuploader) - INFO - Done attempting. 05:24:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:24:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:24:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:19 INFO - (blobuploader) - INFO - Done attempting. 05:24:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:24:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:24:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:20 INFO - (blobuploader) - INFO - Done attempting. 05:24:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:24:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:24:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:26 INFO - (blobuploader) - INFO - Done attempting. 05:24:26 INFO - (blobuploader) - INFO - Iteration through files over. 05:24:26 INFO - Return code: 0 05:24:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:24:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:24:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09dd5180c3324f3404cb863f454f14ba1c9b6ad85bcc311270b2da06613f5f94439ee5fac06b799c59a53cf2681a08bd88f6dde09da7dc67392e115c2a5679ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce4361eda12f259ba0094e1e9068006a3d16e9dbaab6a05ffa95e7e28075f9074721de81be223f97940cf75f6f21fe78f4288c9f911aea9a1ce22ac5b02aaf01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/550dac96c3f97bc5c6f494ef16e6ee64c90e1e7a9c1036213bfd8188c511f7e25b7a289a9604ec3ec1507c6773690e26b699dd66442c5f4b73ef2179599863da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cfbbe95c485c8e3382a498a96e26da32dadae484ee26e3477e864a012529bc2e23bbcf34d4e6ff514c4e2a01916347d2fe286e15c8fd327166fdef0cb0b61ff"} 05:24:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:24:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:24:26 INFO - Contents: 05:24:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09dd5180c3324f3404cb863f454f14ba1c9b6ad85bcc311270b2da06613f5f94439ee5fac06b799c59a53cf2681a08bd88f6dde09da7dc67392e115c2a5679ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce4361eda12f259ba0094e1e9068006a3d16e9dbaab6a05ffa95e7e28075f9074721de81be223f97940cf75f6f21fe78f4288c9f911aea9a1ce22ac5b02aaf01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/550dac96c3f97bc5c6f494ef16e6ee64c90e1e7a9c1036213bfd8188c511f7e25b7a289a9604ec3ec1507c6773690e26b699dd66442c5f4b73ef2179599863da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cfbbe95c485c8e3382a498a96e26da32dadae484ee26e3477e864a012529bc2e23bbcf34d4e6ff514c4e2a01916347d2fe286e15c8fd327166fdef0cb0b61ff"} 05:24:26 INFO - Running post-run listener: copy_logs_to_upload_dir 05:24:26 INFO - Copying logs to upload dir... 05:24:26 INFO - mkdir: /builds/slave/test/build/upload/logs 05:24:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.204649 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-08-08 05:24:26.782643) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:24:26.786009) ========= 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=1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622 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/sha512/09dd5180c3324f3404cb863f454f14ba1c9b6ad85bcc311270b2da06613f5f94439ee5fac06b799c59a53cf2681a08bd88f6dde09da7dc67392e115c2a5679ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce4361eda12f259ba0094e1e9068006a3d16e9dbaab6a05ffa95e7e28075f9074721de81be223f97940cf75f6f21fe78f4288c9f911aea9a1ce22ac5b02aaf01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/550dac96c3f97bc5c6f494ef16e6ee64c90e1e7a9c1036213bfd8188c511f7e25b7a289a9604ec3ec1507c6773690e26b699dd66442c5f4b73ef2179599863da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cfbbe95c485c8e3382a498a96e26da32dadae484ee26e3477e864a012529bc2e23bbcf34d4e6ff514c4e2a01916347d2fe286e15c8fd327166fdef0cb0b61ff"} build_url:https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011499 build_url: 'https://queue.taskcluster.net/v1/task/BYO0e8EGR6OPzlcMpTM4Pw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/09dd5180c3324f3404cb863f454f14ba1c9b6ad85bcc311270b2da06613f5f94439ee5fac06b799c59a53cf2681a08bd88f6dde09da7dc67392e115c2a5679ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce4361eda12f259ba0094e1e9068006a3d16e9dbaab6a05ffa95e7e28075f9074721de81be223f97940cf75f6f21fe78f4288c9f911aea9a1ce22ac5b02aaf01", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/550dac96c3f97bc5c6f494ef16e6ee64c90e1e7a9c1036213bfd8188c511f7e25b7a289a9604ec3ec1507c6773690e26b699dd66442c5f4b73ef2179599863da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3cfbbe95c485c8e3382a498a96e26da32dadae484ee26e3477e864a012529bc2e23bbcf34d4e6ff514c4e2a01916347d2fe286e15c8fd327166fdef0cb0b61ff"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-08 05:24:26.811982) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:24:26.812352) ========= 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=1258088b6da462a47ba2cd90000001a2-1502191672.845924-1743780622 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-08 05:24:26.895982) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:24:26.896341) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-08 05:24:26.896708) ========= ========= Total master_lag: 0.36 =========