builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-057 starttime: 1501675215.07 results: success (0) revision: 52285ea5e54c73d3ed824544cef2ee3f195f05e6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:15.073472) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:15.073991) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:15.074282) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:15.103695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 05:00:15.104010) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-02 05:00:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 217M=0s 2017-08-02 05:00:16 (217 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.057433 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 05:00:16.174914) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:16.175236) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021755 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:16.224552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2017-08-02 05:00:16.224895) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 52285ea5e54c73d3ed824544cef2ee3f195f05e6 --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 52285ea5e54c73d3ed824544cef2ee3f195f05e6 --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=934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-02 05:00:17,024 truncating revision to first 12 chars 2017-08-02 05:00:17,024 Setting DEBUG logging. 2017-08-02 05:00:17,024 attempt 1/10 2017-08-02 05:00:17,024 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/52285ea5e54c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-02 05:00:21,856 unpacking tar archive at: mozilla-central-52285ea5e54c/testing/mozharness/ program finished with exit code 0 elapsedTime=5.161256 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2017-08-02 05:00:21.397851) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:21.398198) ========= script_repo_revision: 52285ea5e54c73d3ed824544cef2ee3f195f05e6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:21.398654) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:21.398956) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 05:00:21.407734) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-08-02 05:00:21.408179) ========= /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=934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:00:22 INFO - MultiFileLogger online at 20170802 05:00:22 in /builds/slave/test 05:00:22 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:00:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:00:22 INFO - {'append_to_log': False, 05:00:22 INFO - 'base_work_dir': '/builds/slave/test', 05:00:22 INFO - 'blob_upload_branch': 'Firefox', 05:00:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:00:22 INFO - 'branch': 'Firefox', 05:00:22 INFO - 'buildbot_json_path': 'buildprops.json', 05:00:22 INFO - 'code_coverage': False, 05:00:22 INFO - 'config_files': ('talos/linux_config.py',), 05:00:22 INFO - 'default_actions': ('clobber', 05:00:22 INFO - 'read-buildbot-config', 05:00:22 INFO - 'download-and-extract', 05:00:22 INFO - 'populate-webroot', 05:00:22 INFO - 'create-virtualenv', 05:00:22 INFO - 'install', 05:00:22 INFO - 'setup-mitmproxy', 05:00:22 INFO - 'run-tests'), 05:00:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:00:22 INFO - 'disable_ccov_upload': False, 05:00:22 INFO - 'download_minidump_stackwalk': True, 05:00:22 INFO - 'download_symbols': 'ondemand', 05:00:22 INFO - 'enable_stylo': False, 05:00:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:00:22 INFO - 'tooltool.py': '/tools/tooltool.py', 05:00:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:00:22 INFO - '/tools/misc-python/virtualenv.py')}, 05:00:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:00:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:00:22 INFO - 'gecko_profile': False, 05:00:22 INFO - 'gecko_profile_interval': 0, 05:00:22 INFO - 'installer_path': 'installer.exe', 05:00:22 INFO - 'log_level': 'info', 05:00:22 INFO - 'log_name': 'talos', 05:00:22 INFO - 'log_to_console': True, 05:00:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:00:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:00:22 INFO - 'opt_config_files': (), 05:00:22 INFO - 'pip_index': False, 05:00:22 INFO - 'suite': 'dromaeojs-e10s', 05:00:22 INFO - 'system_bits': '32', 05:00:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:00:22 INFO - 'title': 'talos-linux64-ix-057', 05:00:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:00:22 INFO - 'use_talos_json': True, 05:00:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:00:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:00:22 INFO - 'work_dir': 'build'} 05:00:22 INFO - [mozharness: 2017-08-02 12:00:22.310568Z] Running clobber step. 05:00:22 INFO - Running pre-action listener: _resource_record_pre_action 05:00:22 INFO - Running main action method: clobber 05:00:22 INFO - rmtree: /builds/slave/test/build 05:00:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:00:23 INFO - Running post-action listener: _resource_record_post_action 05:00:23 INFO - [mozharness: 2017-08-02 12:00:23.366587Z] Finished clobber step (success) 05:00:23 INFO - [mozharness: 2017-08-02 12:00:23.366691Z] Running read-buildbot-config step. 05:00:23 INFO - Running pre-action listener: _resource_record_pre_action 05:00:23 INFO - Running main action method: read_buildbot_config 05:00:23 INFO - Using buildbot properties: 05:00:23 INFO - { 05:00:23 INFO - "project": "", 05:00:23 INFO - "product": "firefox", 05:00:23 INFO - "who": "mtabara@mozilla.com", 05:00:23 INFO - "installer_path": "public/build/target.tar.bz2", 05:00:23 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 05:00:23 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 05:00:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 05:00:23 INFO - "stage_platform": "linux64-pgo", 05:00:23 INFO - "basedir": "/builds/slave/test", 05:00:23 INFO - "buildnumber": 105, 05:00:23 INFO - "platform": "ubuntu64_hw", 05:00:23 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:00:23 INFO - "slavebuilddir": "test", 05:00:23 INFO - "taskId": "V4Fx7ATJTZ6xucFK5kSCgA", 05:00:23 INFO - "branch": "mozilla-central", 05:00:23 INFO - "script_repo_revision": "production", 05:00:23 INFO - "revision": "52285ea5e54c73d3ed824544cef2ee3f195f05e6", 05:00:23 INFO - "slavename": "talos-linux64-ix-057", 05:00:23 INFO - "repo_path": "mozilla-central" 05:00:23 INFO - } 05:00:23 INFO - Finding installer, test and symbols from parent task. 05:00:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V4Fx7ATJTZ6xucFK5kSCgA'}, attempt #1 05:00:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V4Fx7ATJTZ6xucFK5kSCgA'}, attempt #1 05:00:26 INFO - Task dependencies: JoHvQIT-QwS1695eRq3-mg 05:00:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg'}, attempt #1 05:00:27 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.tar.bz2 05:00:27 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.test_packages.json 05:00:27 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.crashreporter-symbols.zip 05:00:27 INFO - Running post-action listener: _resource_record_post_action 05:00:27 INFO - [mozharness: 2017-08-02 12:00:27.780082Z] Finished read-buildbot-config step (success) 05:00:27 INFO - [mozharness: 2017-08-02 12:00:27.780306Z] Running download-and-extract step. 05:00:27 INFO - Running pre-action listener: _resource_record_pre_action 05:00:27 INFO - Running main action method: download_and_extract 05:00:27 INFO - mkdir: /builds/slave/test/build/tests 05:00:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:00:27 INFO - trying https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.test_packages.json 05:00:27 INFO - Downloading https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 05:00:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 05:00:29 INFO - Downloaded 989 bytes. 05:00:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 05:00:29 INFO - Using the following test package requirements: 05:00:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 05:00:29 INFO - u'common': [u'target.common.tests.zip'], 05:00:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 05:00:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 05:00:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 05:00:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 05:00:29 INFO - u'mozbase': [u'target.common.tests.zip'], 05:00:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 05:00:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 05:00:29 INFO - u'web-platform': [u'target.common.tests.zip', 05:00:29 INFO - u'target.web-platform.tests.tar.gz'], 05:00:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 05:00:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 05:00:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.common.tests.zip 05:00:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:00:29 INFO - Fetch https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.common.tests.zip into memory 05:00:31 INFO - Content-Length response header: 73004210 05:00:31 INFO - Bytes received: 73004210 05:00:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 05:00:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.common.tests.zip 05:00:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.common.tests.zip'}, attempt #1 05:00:42 INFO - Fetch https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.common.tests.zip into memory 05:00:44 INFO - Content-Length response header: 73004210 05:00:44 INFO - Bytes received: 73004210 05:00:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.talos.tests.zip 05:00:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 05:00:57 INFO - Fetch https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.talos.tests.zip into memory 05:00:59 INFO - Content-Length response header: 14496467 05:00:59 INFO - Bytes received: 14496467 05:00:59 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:00:59 INFO - trying https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.tar.bz2 05:00:59 INFO - Downloading https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 05:00:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 05:01:02 INFO - Downloaded 63133827 bytes. 05:01:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.tar.bz2 05:01:02 INFO - mkdir: /builds/slave/test/properties 05:01:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:01:02 INFO - Writing to file /builds/slave/test/properties/build_url 05:01:02 INFO - Contents: 05:01:02 INFO - build_url:https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.tar.bz2 05:01:02 INFO - Running post-action listener: _resource_record_post_action 05:01:02 INFO - Running post-action listener: set_extra_try_arguments 05:01:02 INFO - [mozharness: 2017-08-02 12:01:02.325867Z] Finished download-and-extract step (success) 05:01:02 INFO - [mozharness: 2017-08-02 12:01:02.325990Z] Running populate-webroot step. 05:01:02 INFO - Running pre-action listener: _resource_record_pre_action 05:01:02 INFO - Running main action method: populate_webroot 05:01:02 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:01:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:01:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:01:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:01:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:01:02 INFO - 'tests': ['damp', 'tps']}, 05:01:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:01:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:01:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:01:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:01:02 INFO - 'other-e10s': {'tests': ['a11yr', 05:01:02 INFO - 'ts_paint', 05:01:02 INFO - 'tpaint', 05:01:02 INFO - 'sessionrestore', 05:01:02 INFO - 'sessionrestore_many_windows', 05:01:02 INFO - 'sessionrestore_no_auto_restore', 05:01:02 INFO - 'tabpaint']}, 05:01:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 05:01:02 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 05:01:02 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:01:02 INFO - 'talos_options': ['--mitmproxy', 05:01:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:01:02 INFO - '--firstNonBlankPaint'], 05:01:02 INFO - 'tests': ['quantum_pageload_google', 05:01:02 INFO - 'quantum_pageload_youtube', 05:01:02 INFO - 'quantum_pageload_amazon', 05:01:02 INFO - 'quantum_pageload_facebook']}, 05:01:02 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:01:02 INFO - 'talos_options': ['--stylo', 05:01:02 INFO - '--mitmproxy', 05:01:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:01:02 INFO - '--firstNonBlankPaint'], 05:01:02 INFO - 'tests': ['quantum_pageload_google', 05:01:02 INFO - 'quantum_pageload_youtube', 05:01:02 INFO - 'quantum_pageload_amazon', 05:01:02 INFO - 'quantum_pageload_facebook']}, 05:01:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:01:02 INFO - 'tsvgr_opacity', 05:01:02 INFO - 'tart', 05:01:02 INFO - 'tscrollx', 05:01:02 INFO - 'cart', 05:01:02 INFO - 'tsvg_static']}, 05:01:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:01:02 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 05:01:02 INFO - 'talos_options': ['--stylo', 05:01:02 INFO - '--stylo-threads=1', 05:01:02 INFO - '--mitmproxy', 05:01:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 05:01:02 INFO - '--firstNonBlankPaint'], 05:01:02 INFO - 'tests': ['tp6_google', 05:01:02 INFO - 'tp6_youtube', 05:01:02 INFO - 'tp6_amazon', 05:01:02 INFO - 'tp6_facebook']}, 05:01:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:01:02 INFO - 'talos_options': ['--xperf_path', 05:01:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:01:02 INFO - 'tests': ['tp5n']}}} 05:01:02 INFO - Running post-action listener: _resource_record_post_action 05:01:02 INFO - [mozharness: 2017-08-02 12:01:02.337237Z] Finished populate-webroot step (success) 05:01:02 INFO - [mozharness: 2017-08-02 12:01:02.337352Z] Running create-virtualenv step. 05:01:02 INFO - Running pre-action listener: _resource_record_pre_action 05:01:02 INFO - Running main action method: create_virtualenv 05:01:02 INFO - Creating virtualenv /builds/slave/test/build/venv 05:01:02 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:01:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:01:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:01:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:01:02 INFO - Using real prefix '/usr' 05:01:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:01:02 INFO - Installing distribute.............................................................................................................................................................................................done. 05:01:04 INFO - Installing pip.................done. 05:01:04 INFO - Return code: 0 05:01:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:01:05 INFO - Reading from file tmpfile_stdout 05:01:05 INFO - Output received: 05:01:05 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:01:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:01: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:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01: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:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01: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', 'pip>=1.5'] in /builds/slave/test/build 05:01: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 pip>=1.5 05:01:05 INFO - Using env: {'DISPLAY': ':0', 05:01:05 INFO - 'HOME': '/home/cltbld', 05:01:05 INFO - 'LANG': 'en_US.UTF-8', 05:01:05 INFO - 'LANGUAGE': 'en_US:en', 05:01:05 INFO - 'LOGNAME': 'cltbld', 05:01:05 INFO - 'MAIL': '/var/mail/cltbld', 05:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:05 INFO - 'MOZ_NO_REMOTE': '1', 05:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:05 INFO - 'NO_EM_RESTART': '1', 05:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:05 INFO - 'PWD': '/builds/slave/test', 05:01:05 INFO - 'SHELL': '/bin/bash', 05:01:05 INFO - 'SHLVL': '1', 05:01:05 INFO - 'TERM': 'linux', 05:01:05 INFO - 'TMOUT': '86400', 05:01:05 INFO - 'USER': 'cltbld', 05:01:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:05 INFO - '_': '/tools/buildbot/bin/python'} 05:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:05 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:01:05 INFO - Cleaning up... 05:01:05 INFO - Return code: 0 05:01:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:01: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:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01: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:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01: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', '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 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:01: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 psutil>=3.1.1 05:01:06 INFO - Using env: {'DISPLAY': ':0', 05:01:06 INFO - 'HOME': '/home/cltbld', 05:01:06 INFO - 'LANG': 'en_US.UTF-8', 05:01:06 INFO - 'LANGUAGE': 'en_US:en', 05:01:06 INFO - 'LOGNAME': 'cltbld', 05:01:06 INFO - 'MAIL': '/var/mail/cltbld', 05:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:06 INFO - 'MOZ_NO_REMOTE': '1', 05:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:06 INFO - 'NO_EM_RESTART': '1', 05:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:06 INFO - 'PWD': '/builds/slave/test', 05:01:06 INFO - 'SHELL': '/bin/bash', 05:01:06 INFO - 'SHLVL': '1', 05:01:06 INFO - 'TERM': 'linux', 05:01:06 INFO - 'TMOUT': '86400', 05:01:06 INFO - 'USER': 'cltbld', 05:01:06 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:06 INFO - '_': '/tools/buildbot/bin/python'} 05:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:06 INFO - Downloading/unpacking psutil>=3.1.1 05:01:06 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:01:06 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:01:06 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:01:06 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:01:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:01:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:08 INFO - Installing collected packages: psutil 05:01:08 INFO - Running setup.py install for psutil 05:01:08 INFO - building 'psutil._psutil_linux' extension 05:01:08 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:01:09 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:01:09 INFO - building 'psutil._psutil_posix' extension 05:01:09 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:01:09 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:01:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:01:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:01:09 INFO - Successfully installed psutil 05:01:09 INFO - Cleaning up... 05:01:09 INFO - Return code: 0 05:01:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:10 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 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01:10 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:01:10 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:01:10 INFO - Using env: {'DISPLAY': ':0', 05:01:10 INFO - 'HOME': '/home/cltbld', 05:01:10 INFO - 'LANG': 'en_US.UTF-8', 05:01:10 INFO - 'LANGUAGE': 'en_US:en', 05:01:10 INFO - 'LOGNAME': 'cltbld', 05:01:10 INFO - 'MAIL': '/var/mail/cltbld', 05:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:10 INFO - 'MOZ_NO_REMOTE': '1', 05:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:10 INFO - 'NO_EM_RESTART': '1', 05:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:10 INFO - 'PWD': '/builds/slave/test', 05:01:10 INFO - 'SHELL': '/bin/bash', 05:01:10 INFO - 'SHLVL': '1', 05:01:10 INFO - 'TERM': 'linux', 05:01:10 INFO - 'TMOUT': '86400', 05:01:10 INFO - 'USER': 'cltbld', 05:01:10 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:10 INFO - '_': '/tools/buildbot/bin/python'} 05:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:01: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:01: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:01: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:01: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:01:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:01:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:01:12 INFO - Installing collected packages: mozsystemmonitor 05:01:12 INFO - Running setup.py install for mozsystemmonitor 05:01:12 INFO - Successfully installed mozsystemmonitor 05:01:12 INFO - Cleaning up... 05:01:12 INFO - Return code: 0 05:01:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:01:12 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:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:12 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:01:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:01:13 INFO - Using env: {'DISPLAY': ':0', 05:01:13 INFO - 'HOME': '/home/cltbld', 05:01:13 INFO - 'LANG': 'en_US.UTF-8', 05:01:13 INFO - 'LANGUAGE': 'en_US:en', 05:01:13 INFO - 'LOGNAME': 'cltbld', 05:01:13 INFO - 'MAIL': '/var/mail/cltbld', 05:01:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:13 INFO - 'MOZ_NO_REMOTE': '1', 05:01:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:13 INFO - 'NO_EM_RESTART': '1', 05:01:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:13 INFO - 'PWD': '/builds/slave/test', 05:01:13 INFO - 'SHELL': '/bin/bash', 05:01:13 INFO - 'SHLVL': '1', 05:01:13 INFO - 'TERM': 'linux', 05:01:13 INFO - 'TMOUT': '86400', 05:01:13 INFO - 'USER': 'cltbld', 05:01:13 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:13 INFO - '_': '/tools/buildbot/bin/python'} 05:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:13 INFO - Downloading/unpacking jsonschema==2.5.1 05:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:01:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:01: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:01: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:01: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:01: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:01:15 INFO - Downloading functools32-3.2.3-2.tar.gz 05:01:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:01:15 INFO - warning: no files found matching '*.txt' 05:01:15 INFO - no previously-included directories found matching 'build' 05:01:15 INFO - no previously-included directories found matching 'dist' 05:01:15 INFO - no previously-included directories found matching '.git*' 05:01:15 INFO - Installing collected packages: jsonschema, functools32 05:01:15 INFO - Running setup.py install for functools32 05:01:15 INFO - warning: no files found matching '*.txt' 05:01:15 INFO - no previously-included directories found matching 'build' 05:01:15 INFO - no previously-included directories found matching 'dist' 05:01:15 INFO - no previously-included directories found matching '.git*' 05:01:15 INFO - Successfully installed jsonschema functools32 05:01:15 INFO - Cleaning up... 05:01:16 INFO - Return code: 0 05:01:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:01:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:01:16 INFO - Using env: {'DISPLAY': ':0', 05:01:16 INFO - 'HOME': '/home/cltbld', 05:01:16 INFO - 'LANG': 'en_US.UTF-8', 05:01:16 INFO - 'LANGUAGE': 'en_US:en', 05:01:16 INFO - 'LOGNAME': 'cltbld', 05:01:16 INFO - 'MAIL': '/var/mail/cltbld', 05:01:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:16 INFO - 'MOZ_NO_REMOTE': '1', 05:01:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:16 INFO - 'NO_EM_RESTART': '1', 05:01:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:16 INFO - 'PWD': '/builds/slave/test', 05:01:16 INFO - 'SHELL': '/bin/bash', 05:01:16 INFO - 'SHLVL': '1', 05:01:16 INFO - 'TERM': 'linux', 05:01:16 INFO - 'TMOUT': '86400', 05:01:16 INFO - 'USER': 'cltbld', 05:01:16 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:16 INFO - '_': '/tools/buildbot/bin/python'} 05:01:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:01:17 INFO - Cleaning up... 05:01:17 INFO - Return code: 0 05:01:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:01: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:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01: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:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:17 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 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01:17 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:01:17 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:01:17 INFO - Using env: {'DISPLAY': ':0', 05:01:17 INFO - 'HOME': '/home/cltbld', 05:01:17 INFO - 'LANG': 'en_US.UTF-8', 05:01:17 INFO - 'LANGUAGE': 'en_US:en', 05:01:17 INFO - 'LOGNAME': 'cltbld', 05:01:17 INFO - 'MAIL': '/var/mail/cltbld', 05:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:17 INFO - 'MOZ_NO_REMOTE': '1', 05:01:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:17 INFO - 'NO_EM_RESTART': '1', 05:01:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:17 INFO - 'PWD': '/builds/slave/test', 05:01:17 INFO - 'SHELL': '/bin/bash', 05:01:17 INFO - 'SHLVL': '1', 05:01:17 INFO - 'TERM': 'linux', 05:01:17 INFO - 'TMOUT': '86400', 05:01:17 INFO - 'USER': 'cltbld', 05:01:17 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:17 INFO - '_': '/tools/buildbot/bin/python'} 05:01:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:01:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:01:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:21 INFO - Downloading docopt-0.6.1.tar.gz 05:01:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:01:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:01:21 INFO - Running setup.py install for blobuploader 05:01:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:01:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:01:21 INFO - Running setup.py install for requests 05:01:21 INFO - Running setup.py install for docopt 05:01:21 INFO - Successfully installed blobuploader requests docopt 05:01:21 INFO - Cleaning up... 05:01:21 INFO - Return code: 0 05:01:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:22 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 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01:22 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:01:22 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:01:22 INFO - Using env: {'DISPLAY': ':0', 05:01:22 INFO - 'HOME': '/home/cltbld', 05:01:22 INFO - 'LANG': 'en_US.UTF-8', 05:01:22 INFO - 'LANGUAGE': 'en_US:en', 05:01:22 INFO - 'LOGNAME': 'cltbld', 05:01:22 INFO - 'MAIL': '/var/mail/cltbld', 05:01:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:22 INFO - 'MOZ_NO_REMOTE': '1', 05:01:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:22 INFO - 'NO_EM_RESTART': '1', 05:01:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:22 INFO - 'PWD': '/builds/slave/test', 05:01:22 INFO - 'SHELL': '/bin/bash', 05:01:22 INFO - 'SHLVL': '1', 05:01:22 INFO - 'TERM': 'linux', 05:01:22 INFO - 'TMOUT': '86400', 05:01:22 INFO - 'USER': 'cltbld', 05:01:22 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:22 INFO - '_': '/tools/buildbot/bin/python'} 05:01:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:01:22 INFO - Running setup.py (path:/tmp/pip-SGooGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:01:22 INFO - Running setup.py (path:/tmp/pip-3zI28Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:01:22 INFO - Running setup.py (path:/tmp/pip-xADfeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:01:22 INFO - Running setup.py (path:/tmp/pip-W3CLh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:01:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:01:23 INFO - Running setup.py (path:/tmp/pip-4WWeGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:01:23 INFO - Running setup.py (path:/tmp/pip-iXB5XS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:01:23 INFO - Running setup.py (path:/tmp/pip-eIKzVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:01:23 INFO - Running setup.py (path:/tmp/pip-DRA04j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:01:23 INFO - Running setup.py (path:/tmp/pip-nxNEWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:01:23 INFO - Running setup.py (path:/tmp/pip-6hVkD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:01:23 INFO - Running setup.py (path:/tmp/pip-eCia_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:01:23 INFO - Running setup.py (path:/tmp/pip-10wsFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:01:23 INFO - Running setup.py (path:/tmp/pip-0hZ5Ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:01:23 INFO - Running setup.py (path:/tmp/pip-21n4N6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:23 INFO - Running setup.py (path:/tmp/pip-SD73we-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:01:23 INFO - Running setup.py (path:/tmp/pip-hy73du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:01:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:01:23 INFO - Running setup.py (path:/tmp/pip-0M3MtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:01:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:01:23 INFO - Running setup.py install for manifestparser 05:01:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:01:24 INFO - Running setup.py install for mozcrash 05:01:24 INFO - Running setup.py install for mozdebug 05:01:24 INFO - Running setup.py install for mozdevice 05:01:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:01:24 INFO - Running setup.py install for mozfile 05:01:24 INFO - Running setup.py install for mozhttpd 05:01:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:01:24 INFO - Running setup.py install for mozinfo 05:01:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:01:24 INFO - Running setup.py install for mozInstall 05:01:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:01:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:01:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:01:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:01:24 INFO - Running setup.py install for mozleak 05:01:24 INFO - Running setup.py install for mozlog 05:01:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:01:24 INFO - Running setup.py install for moznetwork 05:01:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:01:25 INFO - Running setup.py install for mozprocess 05:01:25 INFO - Running setup.py install for mozprofile 05:01:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:01:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:01:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:01:25 INFO - Running setup.py install for mozrunner 05:01:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:01:25 INFO - Running setup.py install for mozscreenshot 05:01:25 INFO - Running setup.py install for moztest 05:01:25 INFO - Running setup.py install for mozversion 05:01:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:01:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:01:25 INFO - Cleaning up... 05:01:25 INFO - Return code: 0 05:01:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:01:25 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:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:25 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:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:26 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 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01:26 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:01:26 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:01:26 INFO - Using env: {'DISPLAY': ':0', 05:01:26 INFO - 'HOME': '/home/cltbld', 05:01:26 INFO - 'LANG': 'en_US.UTF-8', 05:01:26 INFO - 'LANGUAGE': 'en_US:en', 05:01:26 INFO - 'LOGNAME': 'cltbld', 05:01:26 INFO - 'MAIL': '/var/mail/cltbld', 05:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:26 INFO - 'MOZ_NO_REMOTE': '1', 05:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:26 INFO - 'NO_EM_RESTART': '1', 05:01:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:26 INFO - 'PWD': '/builds/slave/test', 05:01:26 INFO - 'SHELL': '/bin/bash', 05:01:26 INFO - 'SHLVL': '1', 05:01:26 INFO - 'TERM': 'linux', 05:01:26 INFO - 'TMOUT': '86400', 05:01:26 INFO - 'USER': 'cltbld', 05:01:26 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:26 INFO - '_': '/tools/buildbot/bin/python'} 05:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:01:26 INFO - Running setup.py (path:/tmp/pip-NyJfu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:01:26 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:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:01:26 INFO - Running setup.py (path:/tmp/pip-a9uWNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:01:26 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:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:01:26 INFO - Running setup.py (path:/tmp/pip-CGQf0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:01:26 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:01:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:01:26 INFO - Running setup.py (path:/tmp/pip-R_8SR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:01:27 INFO - Running setup.py (path:/tmp/pip-Mq42pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:01:27 INFO - Running setup.py (path:/tmp/pip-nKtfiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:01:27 INFO - Running setup.py (path:/tmp/pip-cS3Ky3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:01:27 INFO - Running setup.py (path:/tmp/pip-piU5Ab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:01:27 INFO - Running setup.py (path:/tmp/pip-bfnnIs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:01:27 INFO - Running setup.py (path:/tmp/pip-Vtp27R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:01:27 INFO - Running setup.py (path:/tmp/pip-ZoqE22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:01:27 INFO - Running setup.py (path:/tmp/pip-5Udyj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:01:27 INFO - Running setup.py (path:/tmp/pip-ckOdbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:01:27 INFO - Running setup.py (path:/tmp/pip-Svl9rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:27 INFO - Running setup.py (path:/tmp/pip-ZFbXw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:01:27 INFO - Running setup.py (path:/tmp/pip-A2Hgc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:01:27 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:01:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:01:27 INFO - Running setup.py (path:/tmp/pip-OJhXaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:01:28 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:01:28 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:01:28 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:01:28 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:01:28 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:01:28 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:01:28 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:01:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:01:28 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:01:28 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:01:28 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:01:28 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:01:30 INFO - Downloading blessings-1.6.tar.gz 05:01:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:01:30 INFO - Installing collected packages: blessings 05:01:30 INFO - Running setup.py install for blessings 05:01:30 INFO - Successfully installed blessings 05:01:30 INFO - Cleaning up... 05:01:30 INFO - Return code: 0 05:01:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:01:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:01:30 INFO - Reading from file tmpfile_stdout 05:01:30 INFO - Current package versions: 05:01:30 INFO - argparse == 1.2.1 05:01:30 INFO - blessings == 1.6 05:01:30 INFO - blobuploader == 1.2.4 05:01:30 INFO - docopt == 0.6.1 05:01:30 INFO - functools32 == 3.2.3-2 05:01:30 INFO - jsonschema == 2.5.1 05:01:30 INFO - manifestparser == 1.1 05:01:30 INFO - mozInstall == 1.13 05:01:30 INFO - mozcrash == 1.0 05:01:30 INFO - mozdebug == 0.1 05:01:30 INFO - mozdevice == 0.51 05:01:30 INFO - mozfile == 1.2 05:01:30 INFO - mozhttpd == 0.7 05:01:30 INFO - mozinfo == 0.10 05:01:30 INFO - mozleak == 0.1 05:01:30 INFO - mozlog == 3.5 05:01:30 INFO - moznetwork == 0.27 05:01:30 INFO - mozprocess == 0.25 05:01:30 INFO - mozprofile == 0.28 05:01:30 INFO - mozrunner == 6.13 05:01:30 INFO - mozscreenshot == 0.1 05:01:30 INFO - mozsystemmonitor == 0.3 05:01:30 INFO - moztest == 0.8 05:01:30 INFO - mozversion == 1.4 05:01:30 INFO - psutil == 3.1.1 05:01:30 INFO - requests == 1.2.3 05:01:30 INFO - wsgiref == 0.1.2 05:01:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:31 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 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01:31 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:01:31 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:01:31 INFO - Using env: {'DISPLAY': ':0', 05:01:31 INFO - 'HOME': '/home/cltbld', 05:01:31 INFO - 'LANG': 'en_US.UTF-8', 05:01:31 INFO - 'LANGUAGE': 'en_US:en', 05:01:31 INFO - 'LOGNAME': 'cltbld', 05:01:31 INFO - 'MAIL': '/var/mail/cltbld', 05:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:31 INFO - 'MOZ_NO_REMOTE': '1', 05:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:31 INFO - 'NO_EM_RESTART': '1', 05:01:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:31 INFO - 'PWD': '/builds/slave/test', 05:01:31 INFO - 'SHELL': '/bin/bash', 05:01:31 INFO - 'SHLVL': '1', 05:01:31 INFO - 'TERM': 'linux', 05:01:31 INFO - 'TMOUT': '86400', 05:01:31 INFO - 'USER': 'cltbld', 05:01:31 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:31 INFO - '_': '/tools/buildbot/bin/python'} 05:01:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:31 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:01:31 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:01:31 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:01:31 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:01:31 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:01:31 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:01:31 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:01:31 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:01:31 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:01:31 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:01:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 05:01:37 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:01:37 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:01:37 INFO - Installing collected packages: simplejson 05:01:37 INFO - Running setup.py install for simplejson 05:01:37 INFO - building 'simplejson._speedups' extension 05:01:37 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:01:38 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:01:38 INFO - Successfully installed simplejson 05:01:38 INFO - Cleaning up... 05:01:38 INFO - Return code: 0 05:01:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:01: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:01:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:01: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:01:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:01:39 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 0x15141f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16e0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1883d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, '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': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', '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:01:39 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:01:39 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:01:39 INFO - Using env: {'DISPLAY': ':0', 05:01:39 INFO - 'HOME': '/home/cltbld', 05:01:39 INFO - 'LANG': 'en_US.UTF-8', 05:01:39 INFO - 'LANGUAGE': 'en_US:en', 05:01:39 INFO - 'LOGNAME': 'cltbld', 05:01:39 INFO - 'MAIL': '/var/mail/cltbld', 05:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:39 INFO - 'MOZ_NO_REMOTE': '1', 05:01:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:39 INFO - 'NO_EM_RESTART': '1', 05:01:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:39 INFO - 'PWD': '/builds/slave/test', 05:01:39 INFO - 'SHELL': '/bin/bash', 05:01:39 INFO - 'SHLVL': '1', 05:01:39 INFO - 'TERM': 'linux', 05:01:39 INFO - 'TMOUT': '86400', 05:01:39 INFO - 'USER': 'cltbld', 05:01:39 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:39 INFO - '_': '/tools/buildbot/bin/python'} 05:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:01:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:01:39 INFO - Cleaning up... 05:01:39 INFO - Return code: 0 05:01:39 INFO - Running post-action listener: _resource_record_post_action 05:01:39 INFO - Running post-action listener: _start_resource_monitoring 05:01:39 INFO - Starting resource monitoring. 05:01:39 INFO - [mozharness: 2017-08-02 12:01:39.575454Z] Finished create-virtualenv step (success) 05:01:39 INFO - [mozharness: 2017-08-02 12:01:39.575958Z] Running install step. 05:01:39 INFO - Running pre-action listener: _resource_record_pre_action 05:01:39 INFO - Running main action method: install 05:01:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:01:39 INFO - Reading from file tmpfile_stdout 05:01:39 INFO - Detecting whether we're running mozinstall >=1.0... 05:01:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:01:39 INFO - Reading from file tmpfile_stdout 05:01:39 INFO - Output received: 05:01:39 INFO - Usage: mozinstall [options] installer 05:01:39 INFO - Options: 05:01:39 INFO - -h, --help show this help message and exit 05:01:39 INFO - -d DEST, --destination=DEST 05:01:39 INFO - Directory to install application into. [default: 05:01:39 INFO - "/builds/slave/test"] 05:01:39 INFO - --app=APP Application being installed. [default: firefox] 05:01:39 INFO - mkdir: /builds/slave/test/build/application 05:01:39 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:01:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 05:01:57 INFO - Reading from file tmpfile_stdout 05:01:57 INFO - Output received: 05:01:57 INFO - /builds/slave/test/build/application/firefox/firefox 05:01:57 INFO - Running post-action listener: _resource_record_post_action 05:01:57 INFO - [mozharness: 2017-08-02 12:01:57.824726Z] Finished install step (success) 05:01:57 INFO - [mozharness: 2017-08-02 12:01:57.824848Z] Running setup-mitmproxy step. 05:01:57 INFO - Running pre-action listener: _resource_record_pre_action 05:01:57 INFO - Running main action method: setup_mitmproxy 05:01:57 INFO - Skipping: mitmproxy is not required 05:01:57 INFO - Running post-action listener: _resource_record_post_action 05:01:57 INFO - [mozharness: 2017-08-02 12:01:57.825647Z] Finished setup-mitmproxy step (success) 05:01:57 INFO - [mozharness: 2017-08-02 12:01:57.825873Z] Running run-tests step. 05:01:57 INFO - Running pre-action listener: _resource_record_pre_action 05:01:57 INFO - Running pre-action listener: _set_gcov_prefix 05:01:57 INFO - Running main action method: run_tests 05:01:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:01:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:01:57 INFO - Python 2.7.3 05:01:57 INFO - Return code: 0 05:01:57 INFO - grabbing minidump binary from tooltool 05:01:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:01:57 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 0x1880eb0>, '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 0x1881db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1882240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:01:57 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:01:57 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:01:57 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:01:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:01:57 INFO - Return code: 0 05:01:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:01:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:01:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:01:57 INFO - ENV: RUST_BACKTRACE is now 1 05:01:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:01:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:01:57 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/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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:01:57 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/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-057 --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:01:57 INFO - Using env: {'DISPLAY': ':0', 05:01:57 INFO - 'HOME': '/home/cltbld', 05:01:57 INFO - 'LANG': 'en_US.UTF-8', 05:01:57 INFO - 'LANGUAGE': 'en_US:en', 05:01:57 INFO - 'LOGNAME': 'cltbld', 05:01:57 INFO - 'MAIL': '/var/mail/cltbld', 05:01:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:01:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:01:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:01:57 INFO - 'MOZ_NO_REMOTE': '1', 05:01:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:01:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:01:57 INFO - 'NO_EM_RESTART': '1', 05:01:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:01:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:01:57 INFO - 'PWD': '/builds/slave/test', 05:01:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:01:57 INFO - 'RUST_BACKTRACE': '1', 05:01:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:01:57 INFO - 'SHELL': '/bin/bash', 05:01:57 INFO - 'SHLVL': '1', 05:01:57 INFO - 'TERM': 'linux', 05:01:57 INFO - 'TMOUT': '86400', 05:01:57 INFO - 'USER': 'cltbld', 05:01:57 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958', 05:01:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:01:57 INFO - '_': '/tools/buildbot/bin/python'} 05:01:57 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/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--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:01:58 INFO - mozversion application_buildid: 20170802081143 05:01:58 INFO - mozversion application_changeset: 52285ea5e54c73d3ed824544cef2ee3f195f05e6 05:01:58 INFO - mozversion application_display_name: Nightly 05:01:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:01:58 INFO - mozversion application_name: Firefox 05:01:58 INFO - mozversion application_remotingname: firefox 05:01:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:01:58 INFO - mozversion application_vendor: Mozilla 05:01:58 INFO - mozversion application_version: 57.0a1 05:01:58 INFO - mozversion platform_buildid: 20170802081143 05:01:58 INFO - mozversion platform_changeset: 52285ea5e54c73d3ed824544cef2ee3f195f05e6 05:01:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:01:58 INFO - mozversion platform_version: 57.0a1 05:01:58 INFO - using testdate: 1501675318 05:01:58 INFO - actual date: 1501675318 05:01:58 INFO - starting webserver on 'localhost:60219' 05:01:58 INFO - SUITE-START | Running 2 tests 05:01:58 INFO - TEST-START | dromaeo_css 05:01:58 INFO - Initialising browser for dromaeo_css test... 05:01:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60219/getInfo.html -profile /tmp/tmpYiNghh/profile 05:01:58 INFO - TEST-INFO | started process 6067 (/builds/slave/test/build/application/firefox/firefox http://localhost:60219/getInfo.html) 05:02:04 INFO - TEST-INFO | 6067: exit 0 05:02:04 INFO - Browser initialized. 05:02:04 INFO - Running cycle 1/1 for dromaeo_css test... 05:02:04 INFO - TEST-INFO | started process 6280 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYiNghh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:02:05 INFO - PID 6280 | 05:02:05 INFO - PID 6280 | (/builds/slave/test/build/application/firefox/firefox:6329): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:02:05 INFO - PID 6280 | 05:02:15 INFO - PID 6280 | RSS: Main: 165236736 05:02:15 INFO - PID 6280 | 05:05:13 INFO - PID 6280 | Cycle 1(1): loaded http://localhost:60219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60219/tests/dromaeo/cssquery-ext.html) 05:05:14 INFO - PID 6280 | RSS: Main: 167292928 05:05:14 INFO - PID 6280 | 05:08:16 INFO - PID 6280 | Cycle 1(1): loaded http://localhost:60219/tests/dromaeo/cssquery-ext.html (next: http://localhost:60219/tests/dromaeo/cssquery-jquery.html) 05:08:17 INFO - PID 6280 | RSS: Main: 164765696 05:08:17 INFO - PID 6280 | 05:11:18 INFO - PID 6280 | Cycle 1(1): loaded http://localhost:60219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60219/tests/dromaeo/cssquery-mootools.html) 05:11:19 INFO - PID 6280 | RSS: Main: 166891520 05:11:19 INFO - PID 6280 | 05:14:20 INFO - PID 6280 | Cycle 1(1): loaded http://localhost:60219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60219/tests/dromaeo/cssquery-prototype.html) 05:14:21 INFO - PID 6280 | RSS: Main: 165412864 05:14:21 INFO - PID 6280 | 05:17:24 INFO - PID 6280 | Cycle 1(1): loaded http://localhost:60219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60219/tests/dromaeo/cssquery-yui.html) 05:17:25 INFO - PID 6280 | RSS: Main: 168960000 05:17:25 INFO - PID 6280 | 05:20:27 INFO - PID 6280 | Cycle 1(1): loaded http://localhost:60219/tests/dromaeo/cssquery-yui.html (next: http://localhost:60219/tests/dromaeo/cssquery-dojo.html) 05:20:27 INFO - PID 6280 | RSS: Main: 169713664 05:20:27 INFO - PID 6280 | 05:20:27 INFO - PID 6280 | __start_tp_report 05:20:27 INFO - PID 6280 | _x_x_mozilla_page_load 05:20:27 INFO - PID 6280 | _x_x_mozilla_page_load_details 05:20:27 INFO - PID 6280 | |i|pagename|runs| 05:20:27 INFO - PID 6280 | |0;dojo.html;3389.3560223557524;3394.1005633507125;3404.1319463536793;3427.794332340058;3456.6197718250987;6071.544634152434;6144.631322120676;6157.199564056672;6228.781992630259;6232.594881332716;6019.969900150504;6121.204243448346;6157.722902469389;6166;6169;5285.154375299952;5298.7350632468315;5396.298481197439;5415.512603865652;5452.04589196889;5367.463253674621;5421.918671219915;5470.452954704538;5496.230527726121;5497.972510137463;5281.656692315007;5353.785848566053;5357.81247656334;5419.5393391561765;5434.130539113762;5350.919736203921;5378.193271009341;5429.619926605139;5439.048166570834;5445;5257.263983042377;5339.3058902342445;5369.892602147975;5426.538744206747;5427.3215848019;5989.730462129215;6021.63870167787;6023.337432882379;6036.245469316335;6060.666663333559;6592.835179120512;6623.105880706091;6680.498962577826;6702.430293425016;6702.731890724365;6734.360235777643;6737.528373013891;6795.762148324785;6816.420604248645;6835.965820170867;6105.816825495198;6188.535859810488;6201.937980620226;6207.596506227081;6279.591826531261;6583.9012414813815;6680.465562754968;6788.185417749902;6801.795946121624;6808.489363297674;6538.738450462023;6546.214454265519;6582.275949645536;6627.1716035495565;6627.403533681992;6650.83372915681;6663.300353462913;6694.062831203635;6744.190697116377;6761.695723692445;6472.870542589122;6589.341065893373;6623.271440141581;6653.63405012729;6693.79918602433;6658.201015878126;6690.297518760557;6692.464602831859;6708.765193218312;6733.989901514714;4707.199776038082;4722.338872557844;4750.572448479654;4777.784999675023;4871.5128487151715;4262.957370426256;4270.316749320156;4276.059266961263;4279.593438623326;4281.486221653359;6189.257289125334;6263.96868015657;6280;6337.746490140435;6363.077353783676;6564.638944857983;6586.04502347157;6628.96685516579;6655.700493477805;6712.261651218362;6234.937650623436;6289.465395441347;6296.464800491918;6325.4939604832425;6327.209098862642;5401.162819762943;5464.87969966156;5468.589855760754;5474.2609747683555;5478.041342765;6816.556923799937;6842.794716158423;6887.449004079662;6892.827679307957;6921;6469.45009674183;6573.408393244631;6654.933450665625;6658.700358483783;6674.365935236145;6896.827579310558;6915.239323674291;6921.792346229621;6984.685689143899;7024.754133605299;6502;6535.215774107138;6547.9672601636685;6585;6585.374389432996;6593.142891424084;6602.702878370389;6696.5647232929705;6707.631080290631;6729.596224226562;6397.7760778374395;6434.967825160844;6444.774432895014;6458.354164583504;6470.191226096738;6480.967595161994;6497.935020649733;6524.2823289439075;6552.312007239171;6559.180102487189;6481.287058423475;6499.902501462388;6532.8366790830605;6539.4768418525455;6556.016597510412;6437.420632143129;6487.675616219076;6618.768343107968;6627.37039981201;6641.701123449359;6259.874802503833;6335.968320158554;6343.080253363144;6358.109864618865;6403.199600049994 05:20:27 INFO - PID 6280 | |1;ext.html;865.189033299273;890.528020149321;922.6125027488454;946.659202687033;952.2001518724275;43109.137817243674;44104.33843492346;44148.77925610376;44151.337729934;44178.779106104506;39461;39769.40345894814;39918.80040599793;40150.59849401505;40227.79886100566;6733.46132309414;6802.727890884359;6844.589324640538;6846.281140480252;6906;6765;6915.1010368652005;6920.515563910529;6921.896171557405;6933.375996160345;7506.774796756106;7665.156832748393;7689.384849212078;7690.192529784376;7693.49992250502;7483.738069167553;7599.506032107895;7643.350315223212;7657.540547567136;7669.6932122715025;7491.550506969599;7534.510256833289;7536.8115797104965;7546.132194797558;7554.282343177444;40450.797746011376;40717.389239161435;40818.183636327405;40846.79576602128;40870;3486.8430920608635;3516.876909308162;3520.2431477232517;3520.6655367740022;3523.066387407339;31820.20449488758;31911.202219944455;31922.361552769045;31943;31957.201069973205;4985.5513003829465;5003;5036.471170527078;5039.118154323016;5058.215976523645;228463;228546;229366;229705;229741;54287;54354.184687229725;54376.72811635916;54428.183577246375;54587.18119228215;57110.42889571134;57383;57416.13875791866;57515;57544.71227643835;15545.378184872705;15708.450204242796;15709.607259818367;15781.21093945321;15800.60498487547;12546;12561.811572826415;12563.93718031404;12568.685782855318;12573.497060117676;15419.383224671112;15459.227038648247;15479.993800402937;15531.922340388226;15630.06219626826;5856.560757943172;6075;6085.269767627913;6102.877942441215;6137.631742095488;1091.4870011094772;1093.1965005720788;1095.386583513235;1101.3832253937821;1104.2104895000114;24587;24653.137140200008;24660;24731.62902556463;24852.37869053288;1187.9524819007315;1195.0977012355615;1197.3055627735891;1197.6287350921243;1206.4631239098517;12922.966162706962;12933.805992910115;13085.869141308656;13130.540431084866;13268.601941941757;282723;283196;283760;283953;284011;56755.43244567584;56837;56853;56917;61766;1512.6369671278815;1537.2006556590734;1542.537238828347;1543.4597890738153;1557.2992153530956;29598.704012960457;29729.25676858096;29803.254918627208;30327.848360758057;30466;39569;39601;39612.207755845295;39654;39698.80150599228;231926;233664;233870;234316;234717;33973.49039764455;34095.31809363749;34176.82911585426;36758.63241367552;37138.62861371352;47703.76148119237;49337.50662493329;49419;49705;49893.25160122674;35381;36458.63541364553;36490.8175459121;36493.635063649024;36596.45105323369;35810.28379432345;35945.281094378486;36321.27357452783;36498;36546.8172659135;40423;40572.594274058065;40625.79687101664;40673.79663101665;41233;34349;34349.656503435646;34445.31109377849;34590;35037.649623503436;26282.73717262803;27082.32294192661;27107.59338609883;27116.32209194786;27548.449031018867 05:20:27 INFO - PID 6280 | |2;jquery.html;577.4831525784423;588.5938702295413;593.1103344982529;594.6818452128109;603.2519675602254;22655.320340389775;23249.418764530852;23528.411789705267;23633.999999999978;23636.5272694546;27337;27599;27795.444091118166;27845.721542784566;27868.16395508138;6166.599171053878;6300.432961033506;6302.369763023707;6303.968480157593;6313.842153946142;5818.4181581841685;6316.178896743417;6326.873462530747;6331.810045698613;6361.4274715275615;6330.525210609199;6332;6343.968280158616;6346.809595712136;6351.079093531435;6327.96836015817;6330.65181415022;6340.746370145189;6341.080543321239;6342.746290148411;5829.300483941912;5861.765529378819;5869.060950247983;5869.8239052828485;5885.382034886318;29729.25676858074;30874.536881946788;30923.690763092305;31054;31320.686793132234;2233.196049422223;2274.8521346112616;2275.0672224388086;2277.851939623936;2278.3620586235716;19560.31538896146;20224;20318.898405508025;20360;20371.694424583642;2323.581755284048;2331.079223706626;2363.0557362297745;2400.543896659629;2448.951020979588;158157;165932;166055.1697241518;166348;166544.99999999878;15675.372985080468;16089.678206435805;16350.264238109314;16385.033795944564;16427.342906283855;23970.88014559951;24048.15831445891;24090.51818963646;24092;24484.755152448248;10064.194864410829;10071.51102649058;10093.848592271122;10318.793624127626;10338.793224135476;8368.58157092143;9121.863172052424;9217.953910230406;9231.123043311009;9273.675421360354;8897.154770296811;9854.556544955494;9915.355501892498;9964.10323070927;10096.394216347042;2740.7122252163235;2754.986225068862;2793.7625301849166;2818.844963527026;2892.262473069396;1057.7567159553346;1067.2742535076068;1076.6985244131656;1084.1326938449367;1085.8859819718973;21830.781692182874;21891.890540547196;22050.558988820132;22050.779492205194;22264.443388915406;1119.960801371948;1127.3461392392392;1131.9434028298551;1134.6199023327113;1141.08713029576;12033.458494367775;12172.695682608006;12301.815472767916;12353.691157720948;12362.196457230431;569247;571562;575258;591721;601403;136042;141536;142763;144618;149904;1314.2114731161228;1321.4648067532664;1358.959231223065;1363.2979015806868;1367.7538043152329;22324.77675223227;22928.19751308763;23118.1908633197;23126.653100203846;23237.070517179123;60388.698056509435;62913.68543157255;63106.36893631005;65030.34969650243;65136.674316628116;81795;83231.16768832477;83265;84258.57870710608;84329;13107;13505.054646175067;13547.79678304846;14895.404183832525;15288.159151246344;24680.876595617625;25594.360140996625;25599.488010240064;25682;25720.871395642902;20052.799472005678;20304.492387690425;20429.897850510653;20487.078081486667;20529.89735051315;16308.103054332125;16953.321867125178;17124;17125.48623541296;17437.564060898578;20708.896455517624;20778.376648700563;20835.166593336704;20849.270275540282;20908.895455522626;14679.266036698336;14796.112233266038;14880.7767883484;14935.626609334418;14953.102813831205;20114.19543218255;20135.798642013393;20232.89883550573;20411.79588204099;20429.08069136863 05:20:27 INFO - PID 6280 | |3;mootools.html;1365.8838998685112;1395.2117053864565;1409.1545072956224;1434.4047220403534;1436.5977526292643;15026.549203523899;15182.316795744191;15464.92267538662;15477.535673929786;15676.294566744495;15754.527364179094;16009.83990160098;16028.839711602881;16029.038257704546;16032.679346413093;2666.8666566671686;2715.891364345424;2716.2666080158347;2717.184844546628;2717.9728202717915;2627.5927231279184;2640.366312085095;2676.571748520237;2727.9318017049536;2728.058819707198;2554.693436787588;2557.6802899637546;2573.5496288149593;2577.5231582157267;2581.7676409123173;2608.8565128917903;2650.2446802661248;2659.6143559184;2661.3745769744096;2663.507251158532;2614.647022651937;2633.0652618320605;2634.1570697376847;2643;2645.2857728413414;14887.702245955128;15236.847631523653;15375.538733838002;15377.692446151013;15395.230238488142;7567.394608431312;7781.144074151839;7783.532988020737;7785.299323060895;7787.260210280015;5203.19350500673;5370.946290537084;5410.864728381783;5422.783088676449;5466.590005749585;1908.1413363986262;1933.729277901095;1962.9010711662506;1988.4929343017582;2009.437357539891;26223.475530489282;26292.342691432867;26327.868360658267;26479.47041059187;26515.867420662773;6796.558223715541;6951.756688515979;6995.300469952964;7092.787216383516;7155.964220178866;17972.640547189243;18098.90950545265;18113.909430453026;18132.546686332946;18136.274549018155;8105.959470202729;8322.833543329221;8374.916250837536;8377.078521362646;8380.287675547646;7471.8505629887095;7605.087389513294;7651.196624354363;7663.042119735033;7729.729459468892;4990.30135780991;5251.2123181523075;5353.678779273256;5428.104362780097;5445.346558413015;1455.294182321573;1563.5309407177801;1566.6083479130218;1605.8875878688614;1608.6460978584694;883.1257055514994;900.4687234531658;900.819836032796;909.5406819556082;914.7164379042518;8050.758477245692;8287.8342433151;8302.294304984023;8313.875291870627;8448.83102337962;997.7356000659831;1000.9199264058858;1004.3973615830589;1015.918726501878;1017.2014968249888;4921.483244259372;5201.349831271091;5355.223492593552;5366.356037275552;5408.269883565668;20862;21212.57574848525;21473.14107435716;21473.785262147492;21948.780512194993;21449.356519304445;21779.67330490044;22086.11655533793;22098;22424.327270181922;11513.481893314822;11526.654200373834;11809.704757381134;11860.999999999827;11887.940560297144;8363.916360836558;8507.319414446953;8582.828343432971;8633.438826476258;8640.35197360213;19280.325188618892;19281.710774338117;19439.12523936398;19460.805391945898;19883;19773.70339444938;19808.70286945726;19868.503287417352;19897.900510497355;19952.900235498728;12841.35793210317;13190.406431710788;13192.934035329761;13208.27354495512;13220.933895330461;13117.475300987853;14380.784288235476;14522;14620.049696769676;14740.852591474377;10801;11516.712082198012;11721.88278117242;11888.821667674818;12072.75854482923;11028.779424411627;11082.279651822606;11084.667459976214;11093.667189984313;11347.54609815598;9591.472469014272;9808.705738827846;9821.263405244777;9933.255005874444;9936.056074672895;7272.454565907472;7368.189499155196;7374.078240219973;7432.554046757212;7489.737859174903;7350.411967042731;7396.778096657109;7420.480566360519;7441.888371674321;7536.1333446654335 05:20:27 INFO - PID 6280 | |4;prototype.html;499.25610839848616;510.22955337440476;531.2721571447116;534.8475684429939;536.8657835541114;6028.306744724358;6073.392660733935;6115.449609535141;6123.3876612338745;6147.323794382625;5811.099279611667;5960.672163031031;5963.194968679238;6043.425874541911;6044.879102417949;4639.141758774637;4705.364775755272;4725.220338644137;4746.881327966794;4747.928781068286;4605.815767369301;4677.789499472515;4684.05611671156;4704.0356726870905;4725.362076119732;4658.114958157956;4663.347131401607;4667.953320466786;4675.906481870361;4704.670673052888;4527.750973696446;4612.976935115336;4659.4175728034;4676.321933319684;4684.5549672781035;4265.7014009019385;4282.122164956177;4283.372318518943;4300.1399720056115;4306.224879521685;5943.554233432466;5985.25184351956;6006.879862402771;6008.909866352008;6010.549208809357;5596.804111856106;5659.330497503246;5660.235868157787;5699.316082070178;5736.110902810071;6435.549511534195;6455.451286640641;6456.580322279036;6471.385218404244;6474.579152355129;4857.295692124622;4868.904496488297;4876.878078048042;4877.634177436671;4902.362692849907;6691.76578819739;6723.495737819683;6729.798106056826;6733.730650773914;6783.3555812197765;4974.228994505857;4979.30289759434;5002.524760147853;5017.569992552105;5044.1677123274985;5087.720175390389;5096.286519887219;5108.9744551277745;5109.642325037212;5139.383274007143;5684.914726279109;5686.175504551817;5697.914531282034;5726.599138060379;5736.827895163152;6397.296297407282;6448.9355106449275;6454.709058188381;6465.3857883501;6507.446867016357;1663.1601041473978;1702.8297170282872;1718.4157386488405;1738.078818226342;1743.5553933746814;1211.1461419699092;1216.4586758892385;1218.5369559567307;1232.3037483821613;1239.3431481314917;6156.6921653917125;6191.597546159485;6212.47193988516;6233.688315584202;6248.250209974741;6339.873202535923;6473.158489396443;6482.67586620667;6573.5677799213645;6671.899921501182;6185.721642526097;6192.783252586228;6193.142057382857;6220.191375121205;6320.905186422207;3436.914077148041;3481.1993241554583;3490.1798077452286;3504.404251277289;3532.5339983906906;6877.89683154753;6890.689918953659;6938.930610693828;6939.20199177091;7006.229314775371;6770.984352347187;6820;6830.624315662686;6841.255479852605;6844.1444819397575;6926.9307306926285;6943.132108486439;6956.686949087303;6970.059042029261;6970.512064155562;6567.934320656732;6589.604623722592;6645.767398141042;6649.833754156185;6668.499862510428;6578.276389597237;6587.934120658732;6611.330711455189;6616.2060552732055;6645.568038077509;6856.280090590616;6894.620795856276;6917.723291068301;6937.231006093414;6970.477214208853;4959.677620954771;4982.451930287593;5008.173651347487;5008.373953255843;5037.496250374933;6407.967960160169;6565.770198043237;6633.124653379666;6666.400023997863;6682.766103186365;6169.784057557964;6260.436560709558;6322.525810564134;6426.29310775805;6451.3548645135115;6339.461145802658;6347.143135676624;6355.841103972438;6416.550841441054;6436.839079022875;6178.351273116258;6289;6376.713047912763;6377.107204991212;6397.0404439332615;6311.968440157953;6380.617162970237;6386.828478317394;6412.6793660317735;6439.517036222208;6220.2535695715;6249.37536228977;6277.591956522811;6285.214348206474;6324.6521441321165 05:20:27 INFO - PID 6280 | |5;yui.html;922.5986695787333;928.2713070239861;944.8299306124903;953.5994882149497;957.7988622389296;18045.63908721825;18749.34377296795;18791.154398052087;18815.153318100685;22524.211652592163;20737.274195403163;21475.140994360245;21549.56900861982;21560.784392156074;21620.8918955405;996.7358649957785;1022.3201570955306;1025.0467065628961;1027.0345874877628;1027.1782573940854;1012.7265638277661;1082.2532452607675;1083.312096818522;1083.5178345636195;1084.3114622214912;1118.7091356247354;1123.6516679829238;1124.0557931337676;1126.3748619516207;1127.6391554702498;1094.4691824465208;1095.7534554725203;1098.6154845803985;1100.070440477799;1109.0295991007868;804.7022601637373;810.2748040503716;811.0915774332726;819.2831272636444;820.6717313074759;16626.50120496387;16645.25096370666;16700.164991750364;16729.330826766916;19691.11389987454;18582.256709731595;18951.33670321546;18987;18999.145038473303;19506.80493195064;16083.839161608352;16489.752653710202;16637.4176903809;16786.328546858113;16790.16049197535;3652.853885844563;3652.9223878955772;3656.1042544576485;3656.4149736042104;3660.7071434285194;137210;141633.2918335412;142173;142920.99999999895;143778;16882.74675879863;17061.829381706273;17295.135243237786;17456.563585910455;17546.122693865254;31944.201394964846;32496.83751581227;32983;33263.004219746406;33298.66701333004;7548.509346892434;7925.8811117833275;7957.920420795834;8315;9133.086691330815;4286.035641980592;4376.387305777193;4400.75795831226;4418.469783625986;4428.092241090569;5322.33470816148;5361.195820626859;5386.48828361305;5434.076207044812;5438.619296649196;1050.1808589300358;1077.6443773554709;1078.5092782783815;1092.983605245922;1093.2074246171621;723.4176487927164;736.6095969136367;745.5712965045012;752.834376437183;757.1293013034943;12005.819912701316;12847.743045139232;13052.869471305165;13358.732825343439;13377.197368157747;701.214639603637;711.6086152616041;713.6039498078616;719.0616245799177;726.0488759724777;5757.510611598061;6713.664316784141;6851.520393572402;7010.298970103051;7059.470539709542;123078.38460807818;123690.38154809168;127546;129081.35459322644;132878;108458;110265;111950.44024779987;111996;112046;98127;100359.49820250852;102521.9999999985;102760;107252;13420.798688019493;13703.109297895606;13759.380827862971;13902.582922512342;13972.371243293874;46267.53732462767;48058.75970620124;48119;48733.5126648729;49716;60974;63145.68427157835;63280.36719632745;63336.36663633305;64271.35728642841;18627.441176764718;19091.522711931757;19322.710159347338;19352;19370.903145484182;22010.669839952094;22473;22485.21301754496;22515;22731.65902511497;19390.903045484683;19745.802541974397;19927;19952.800471995673;19954.70067949011;20465.283715070498;20581.176752929714;20673.379798606067;20723;20762.584748305253;18292.542686432946;18393.54016149607;18493.352732654294;18497.81502184961;18579.721304180177;14183.432662693376;14285.28573571338;14290.999630025799;14358.641033974234;14702;14888.62778430548;14967.251637417869;15129.319180636678;15139.697206056038;15337.309821057856 05:20:27 INFO - PID 6280 | __end_tp_report 05:20:27 INFO - PID 6280 | __start_cc_report 05:20:27 INFO - PID 6280 | _x_x_mozilla_cycle_collect,333 05:20:27 INFO - PID 6280 | __end_cc_report 05:20:27 INFO - PID 6280 | __startTimestamp1501676427253__endTimestamp 05:20:27 INFO - PID 6280 | 05:20:27 INFO - PID 6280 | ------- Summary: start ------- 05:20:27 INFO - PID 6280 | Number of tests: 6 05:20:27 INFO - PID 6280 | 05:20:27 INFO - PID 6280 | [#0] dojo.html Cycles:165 Average:6103.09 Median:6437.42 stddev:798.98 (12.4%) stddev-sans-first:772.55 05:20:27 INFO - PID 6280 | Values: 3389.4 3394.1 3404.1 3427.8 3456.6 6071.5 6144.6 6157.2 6228.8 6232.6 6020.0 6121.2 6157.7 6166.0 6169.0 5285.2 5298.7 5396.3 5415.5 5452.0 5367.5 5421.9 5470.5 5496.2 5498.0 5281.7 5353.8 5357.8 5419.5 5434.1 5350.9 5378.2 5429.6 5439.0 5445.0 5257.3 5339.3 5369.9 5426.5 5427.3 5989.7 6021.6 6023.3 6036.2 6060.7 6592.8 6623.1 6680.5 6702.4 6702.7 6734.4 6737.5 6795.8 6816.4 6836.0 6105.8 6188.5 6201.9 6207.6 6279.6 6583.9 6680.5 6788.2 6801.8 6808.5 6538.7 6546.2 6582.3 6627.2 6627.4 6650.8 6663.3 6694.1 6744.2 6761.7 6472.9 6589.3 6623.3 6653.6 6693.8 6658.2 6690.3 6692.5 6708.8 6734.0 4707.2 4722.3 4750.6 4777.8 4871.5 4263.0 4270.3 4276.1 4279.6 4281.5 6189.3 6264.0 6280.0 6337.7 6363.1 6564.6 6586.0 6629.0 6655.7 6712.3 6234.9 6289.5 6296.5 6325.5 6327.2 5401.2 5464.9 5468.6 5474.3 5478.0 6816.6 6842.8 6887.4 6892.8 6921.0 6469.5 6573.4 6654.9 6658.7 6674.4 6896.8 6915.2 6921.8 6984.7 7024.8 6502.0 6535.2 6548.0 6585.0 6585.4 6593.1 6602.7 6696.6 6707.6 6729.6 6397.8 6435.0 6444.8 6458.4 6470.2 6481.0 6497.9 6524.3 6552.3 6559.2 6481.3 6499.9 6532.8 6539.5 6556.0 6437.4 6487.7 6618.8 6627.4 6641.7 6259.9 6336.0 6343.1 6358.1 6403.2 05:20:27 INFO - PID 6280 | 05:20:27 INFO - PID 6280 | [#1] ext.html Cycles:180 Average:42743.06 Median:36522.41 stddev:65089.63 (178.2%) stddev-sans-first:65196.27 05:20:27 INFO - PID 6280 | Values: 865.2 890.5 922.6 946.7 952.2 43109.1 44104.3 44148.8 44151.3 44178.8 39461.0 39769.4 39918.8 40150.6 40227.8 6733.5 6802.7 6844.6 6846.3 6906.0 6765.0 6915.1 6920.5 6921.9 6933.4 7506.8 7665.2 7689.4 7690.2 7693.5 7483.7 7599.5 7643.4 7657.5 7669.7 7491.6 7534.5 7536.8 7546.1 7554.3 40450.8 40717.4 40818.2 40846.8 40870.0 3486.8 3516.9 3520.2 3520.7 3523.1 31820.2 31911.2 31922.4 31943.0 31957.2 4985.6 5003.0 5036.5 5039.1 5058.2 228463.0 228546.0 229366.0 229705.0 229741.0 54287.0 54354.2 54376.7 54428.2 54587.2 57110.4 57383.0 57416.1 57515.0 57544.7 15545.4 15708.5 15709.6 15781.2 15800.6 12546.0 12561.8 12563.9 12568.7 12573.5 15419.4 15459.2 15480.0 15531.9 15630.1 5856.6 6075.0 6085.3 6102.9 6137.6 1091.5 1093.2 1095.4 1101.4 1104.2 24587.0 24653.1 24660.0 24731.6 24852.4 1188.0 1195.1 1197.3 1197.6 1206.5 12923.0 12933.8 13085.9 13130.5 13268.6 282723.0 283196.0 283760.0 283953.0 284011.0 56755.4 56837.0 56853.0 56917.0 61766.0 1512.6 1537.2 1542.5 1543.5 1557.3 29598.7 29729.3 29803.3 30327.8 30466.0 39569.0 39601.0 39612.2 39654.0 39698.8 231926.0 233664.0 233870.0 234316.0 234717.0 33973.5 34095.3 34176.8 36758.6 37138.6 47703.8 49337.5 49419.0 49705.0 49893.3 35381.0 36458.6 36490.8 36493.6 36596.5 35810.3 35945.3 36321.3 36498.0 36546.8 40423.0 40572.6 40625.8 40673.8 41233.0 34349.0 34349.7 34445.3 34590.0 35037.6 26282.7 27082.3 27107.6 27116.3 27548.4 05:20:27 INFO - PID 6280 | 05:20:27 INFO - PID 6280 | [#2] jquery.html Cycles:180 Average:40000.58 Median:12725.12 stddev:98509.42 (774.1%) stddev-sans-first:98741.30 05:20:27 INFO - PID 6280 | Values: 577.5 588.6 593.1 594.7 603.3 22655.3 23249.4 23528.4 23634.0 23636.5 27337.0 27599.0 27795.4 27845.7 27868.2 6166.6 6300.4 6302.4 6304.0 6313.8 5818.4 6316.2 6326.9 6331.8 6361.4 6330.5 6332.0 6344.0 6346.8 6351.1 6328.0 6330.7 6340.7 6341.1 6342.7 5829.3 5861.8 5869.1 5869.8 5885.4 29729.3 30874.5 30923.7 31054.0 31320.7 2233.2 2274.9 2275.1 2277.9 2278.4 19560.3 20224.0 20318.9 20360.0 20371.7 2323.6 2331.1 2363.1 2400.5 2449.0 158157.0 165932.0 166055.2 166348.0 166545.0 15675.4 16089.7 16350.3 16385.0 16427.3 23970.9 24048.2 24090.5 24092.0 24484.8 10064.2 10071.5 10093.8 10318.8 10338.8 8368.6 9121.9 9218.0 9231.1 9273.7 8897.2 9854.6 9915.4 9964.1 10096.4 2740.7 2755.0 2793.8 2818.8 2892.3 1057.8 1067.3 1076.7 1084.1 1085.9 21830.8 21891.9 22050.6 22050.8 22264.4 1120.0 1127.3 1131.9 1134.6 1141.1 12033.5 12172.7 12301.8 12353.7 12362.2 569247.0 571562.0 575258.0 591721.0 601403.0 136042.0 141536.0 142763.0 144618.0 149904.0 1314.2 1321.5 1359.0 1363.3 1367.8 22324.8 22928.2 23118.2 23126.7 23237.1 60388.7 62913.7 63106.4 65030.3 65136.7 81795.0 83231.2 83265.0 84258.6 84329.0 13107.0 13505.1 13547.8 14895.4 15288.2 24680.9 25594.4 25599.5 25682.0 25720.9 20052.8 20304.5 20429.9 20487.1 20529.9 16308.1 16953.3 17124.0 17125.5 17437.6 20708.9 20778.4 20835.2 20849.3 20908.9 14679.3 14796.1 14880.8 14935.6 14953.1 20114.2 20135.8 20232.9 20411.8 20429.1 05:20:27 INFO - PID 6280 | 05:20:27 INFO - PID 6280 | [#3] mootools.html Cycles:180 Average:9547.98 Median:14419.06 stddev:6819.54 (47.3%) stddev-sans-first:6810.96 05:20:27 INFO - PID 6280 | Values: 1365.9 1395.2 1409.2 1434.4 1436.6 15026.5 15182.3 15464.9 15477.5 15676.3 15754.5 16009.8 16028.8 16029.0 16032.7 2666.9 2715.9 2716.3 2717.2 2718.0 2627.6 2640.4 2676.6 2727.9 2728.1 2554.7 2557.7 2573.5 2577.5 2581.8 2608.9 2650.2 2659.6 2661.4 2663.5 2614.6 2633.1 2634.2 2643.0 2645.3 14887.7 15236.8 15375.5 15377.7 15395.2 7567.4 7781.1 7783.5 7785.3 7787.3 5203.2 5370.9 5410.9 5422.8 5466.6 1908.1 1933.7 1962.9 1988.5 2009.4 26223.5 26292.3 26327.9 26479.5 26515.9 6796.6 6951.8 6995.3 7092.8 7156.0 17972.6 18098.9 18113.9 18132.5 18136.3 8106.0 8322.8 8374.9 8377.1 8380.3 7471.9 7605.1 7651.2 7663.0 7729.7 4990.3 5251.2 5353.7 5428.1 5445.3 1455.3 1563.5 1566.6 1605.9 1608.6 883.1 900.5 900.8 909.5 914.7 8050.8 8287.8 8302.3 8313.9 8448.8 997.7 1000.9 1004.4 1015.9 1017.2 4921.5 5201.3 5355.2 5366.4 5408.3 20862.0 21212.6 21473.1 21473.8 21948.8 21449.4 21779.7 22086.1 22098.0 22424.3 11513.5 11526.7 11809.7 11861.0 11887.9 8363.9 8507.3 8582.8 8633.4 8640.4 19280.3 19281.7 19439.1 19460.8 19883.0 19773.7 19808.7 19868.5 19897.9 19952.9 12841.4 13190.4 13192.9 13208.3 13220.9 13117.5 14380.8 14522.0 14620.0 14740.9 10801.0 11516.7 11721.9 11888.8 12072.8 11028.8 11082.3 11084.7 11093.7 11347.5 9591.5 9808.7 9821.3 9933.3 9936.1 7272.5 7368.2 7374.1 7432.6 7489.7 7350.4 7396.8 7420.5 7441.9 7536.1 05:20:27 INFO - PID 6280 | 05:20:27 INFO - PID 6280 | [#4] prototype.html Cycles:180 Average:5474.12 Median:6163.24 stddev:1568.92 (25.5%) stddev-sans-first:1528.24 05:20:27 INFO - PID 6280 | Values: 499.3 510.2 531.3 534.8 536.9 6028.3 6073.4 6115.4 6123.4 6147.3 5811.1 5960.7 5963.2 6043.4 6044.9 4639.1 4705.4 4725.2 4746.9 4747.9 4605.8 4677.8 4684.1 4704.0 4725.4 4658.1 4663.3 4668.0 4675.9 4704.7 4527.8 4613.0 4659.4 4676.3 4684.6 4265.7 4282.1 4283.4 4300.1 4306.2 5943.6 5985.3 6006.9 6008.9 6010.5 5596.8 5659.3 5660.2 5699.3 5736.1 6435.5 6455.5 6456.6 6471.4 6474.6 4857.3 4868.9 4876.9 4877.6 4902.4 6691.8 6723.5 6729.8 6733.7 6783.4 4974.2 4979.3 5002.5 5017.6 5044.2 5087.7 5096.3 5109.0 5109.6 5139.4 5684.9 5686.2 5697.9 5726.6 5736.8 6397.3 6448.9 6454.7 6465.4 6507.4 1663.2 1702.8 1718.4 1738.1 1743.6 1211.1 1216.5 1218.5 1232.3 1239.3 6156.7 6191.6 6212.5 6233.7 6248.3 6339.9 6473.2 6482.7 6573.6 6671.9 6185.7 6192.8 6193.1 6220.2 6320.9 3436.9 3481.2 3490.2 3504.4 3532.5 6877.9 6890.7 6938.9 6939.2 7006.2 6771.0 6820.0 6830.6 6841.3 6844.1 6926.9 6943.1 6956.7 6970.1 6970.5 6567.9 6589.6 6645.8 6649.8 6668.5 6578.3 6587.9 6611.3 6616.2 6645.6 6856.3 6894.6 6917.7 6937.2 6970.5 4959.7 4982.5 5008.2 5008.4 5037.5 6408.0 6565.8 6633.1 6666.4 6682.8 6169.8 6260.4 6322.5 6426.3 6451.4 6339.5 6347.1 6355.8 6416.6 6436.8 6178.4 6289.0 6376.7 6377.1 6397.0 6312.0 6380.6 6386.8 6412.7 6439.5 6220.3 6249.4 6277.6 6285.2 6324.7 05:20:27 INFO - PID 6280 | 05:20:27 INFO - PID 6280 | [#5] yui.html Cycles:180 Average:25841.38 Median:19045.33 stddev:36391.44 (191.1%) stddev-sans-first:36445.43 05:20:27 INFO - PID 6280 | Values: 922.6 928.3 944.8 953.6 957.8 18045.6 18749.3 18791.2 18815.2 22524.2 20737.3 21475.1 21549.6 21560.8 21620.9 996.7 1022.3 1025.0 1027.0 1027.2 1012.7 1082.3 1083.3 1083.5 1084.3 1118.7 1123.7 1124.1 1126.4 1127.6 1094.5 1095.8 1098.6 1100.1 1109.0 804.7 810.3 811.1 819.3 820.7 16626.5 16645.3 16700.2 16729.3 19691.1 18582.3 18951.3 18987.0 18999.1 19506.8 16083.8 16489.8 16637.4 16786.3 16790.2 3652.9 3652.9 3656.1 3656.4 3660.7 137210.0 141633.3 142173.0 142921.0 143778.0 16882.7 17061.8 17295.1 17456.6 17546.1 31944.2 32496.8 32983.0 33263.0 33298.7 7548.5 7925.9 7957.9 8315.0 9133.1 4286.0 4376.4 4400.8 4418.5 4428.1 5322.3 5361.2 5386.5 5434.1 5438.6 1050.2 1077.6 1078.5 1093.0 1093.2 723.4 736.6 745.6 752.8 757.1 12005.8 12847.7 13052.9 13358.7 13377.2 701.2 711.6 713.6 719.1 726.0 5757.5 6713.7 6851.5 7010.3 7059.5 123078.4 123690.4 127546.0 129081.4 132878.0 108458.0 110265.0 111950.4 111996.0 112046.0 98127.0 100359.5 102522.0 102760.0 107252.0 13420.8 13703.1 13759.4 13902.6 13972.4 46267.5 48058.8 48119.0 48733.5 49716.0 60974.0 63145.7 63280.4 63336.4 64271.4 18627.4 19091.5 19322.7 19352.0 19370.9 22010.7 22473.0 22485.2 22515.0 22731.7 19390.9 19745.8 19927.0 19952.8 19954.7 20465.3 20581.2 20673.4 20723.0 20762.6 18292.5 18393.5 18493.4 18497.8 18579.7 14183.4 14285.3 14291.0 14358.6 14702.0 14888.6 14967.3 15129.3 15139.7 15337.3 05:20:27 INFO - PID 6280 | -------- Summary: end -------- 05:20:27 INFO - PID 6280 | 05:20:27 INFO - TEST-INFO | 6280: exit 0 05:20:27 INFO - TEST-OK | dromaeo_css | took 1109787ms 05:20:27 INFO - TEST-START | kraken 05:20:27 INFO - Initialising browser for kraken test... 05:20:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:60219/getInfo.html -profile /tmp/tmpHGIPux/profile 05:20:27 INFO - TEST-INFO | started process 6648 (/builds/slave/test/build/application/firefox/firefox http://localhost:60219/getInfo.html) 05:20:33 INFO - TEST-INFO | 6648: exit 0 05:20:33 INFO - Browser initialized. 05:20:33 INFO - Running cycle 1/1 for kraken test... 05:20:33 INFO - TEST-INFO | started process 6858 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHGIPux/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 05:20:34 INFO - PID 6858 | 05:20:34 INFO - PID 6858 | (/builds/slave/test/build/application/firefox/firefox:6907): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 05:20:34 INFO - PID 6858 | 05:20:45 INFO - PID 6858 | RSS: Main: 165322752 05:20:45 INFO - PID 6858 | 05:20:47 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60219/tests/kraken/driver.html?testName=audio-beat-detection) 05:20:48 INFO - PID 6858 | RSS: Main: 169816064 05:20:48 INFO - PID 6858 | 05:20:51 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60219/tests/kraken/driver.html?testName=audio-dft) 05:20:51 INFO - PID 6858 | RSS: Main: 170627072 05:20:51 INFO - PID 6858 | 05:20:54 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60219/tests/kraken/driver.html?testName=audio-fft) 05:20:55 INFO - PID 6858 | RSS: Main: 165212160 05:20:55 INFO - PID 6858 | 05:20:57 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60219/tests/kraken/driver.html?testName=audio-oscillator) 05:20:57 INFO - PID 6858 | RSS: Main: 166338560 05:20:57 INFO - PID 6858 | 05:21:00 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:21:00 INFO - PID 6858 | RSS: Main: 166608896 05:21:00 INFO - PID 6858 | 05:21:03 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60219/tests/kraken/driver.html?testName=imaging-darkroom) 05:21:04 INFO - PID 6858 | RSS: Main: 167940096 05:21:04 INFO - PID 6858 | 05:21:07 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60219/tests/kraken/driver.html?testName=imaging-desaturate) 05:21:08 INFO - PID 6858 | RSS: Main: 171085824 05:21:08 INFO - PID 6858 | 05:21:11 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60219/tests/kraken/driver.html?testName=json-parse-financial) 05:21:11 INFO - PID 6858 | RSS: Main: 169500672 05:21:11 INFO - PID 6858 | 05:21:13 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:21:13 INFO - PID 6858 | RSS: Main: 169181184 05:21:13 INFO - PID 6858 | 05:21:15 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60219/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:21:16 INFO - PID 6858 | RSS: Main: 169259008 05:21:16 INFO - PID 6858 | 05:21:18 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:21:18 INFO - PID 6858 | RSS: Main: 166375424 05:21:18 INFO - PID 6858 | 05:21:21 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:21:22 INFO - PID 6858 | RSS: Main: 166412288 05:21:22 INFO - PID 6858 | 05:21:25 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:21:25 INFO - PID 6858 | RSS: Main: 166916096 05:21:25 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | Cycle 1(1): loaded http://localhost:60219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60219/tests/kraken/driver.html?testName=ai-astar) 05:21:27 INFO - PID 6858 | RSS: Main: 168914944 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | __start_tp_report 05:21:27 INFO - PID 6858 | _x_x_mozilla_page_load 05:21:27 INFO - PID 6858 | _x_x_mozilla_page_load_details 05:21:27 INFO - PID 6858 | |i|pagename|runs| 05:21:27 INFO - PID 6858 | |0;ai-astar;96;106;102;106;106;105;107;98;97;108 05:21:27 INFO - PID 6858 | |1;audio-beat-detection;136;111;141;172;115;147;141;127;170;120 05:21:27 INFO - PID 6858 | |2;audio-dft;164;168;162;162;161;164;163;161;167;171 05:21:27 INFO - PID 6858 | |3;audio-fft;74;75;76;168;80;79;77;91;100;74 05:21:27 INFO - PID 6858 | |4;audio-oscillator;79;79;129;89;78;78;124;78;79;132 05:21:27 INFO - PID 6858 | |5;imaging-gaussian-blur;114;115;115;114;115;114;114;120;114;115 05:21:27 INFO - PID 6858 | |6;imaging-darkroom;149;150;149;149;148;149;148;151;148;149 05:21:27 INFO - PID 6858 | |7;imaging-desaturate;94;95;94;94;94;94;95;95;95;94 05:21:27 INFO - PID 6858 | |8;json-parse-financial;49;48;47;49;47;49;50;60;47;47 05:21:27 INFO - PID 6858 | |9;json-stringify-tinderbox;55;55;55;54;54;54;55;54;54;55 05:21:27 INFO - PID 6858 | |10;stanford-crypto-aes;75;73;74;73;81;77;74;74;74;76 05:21:27 INFO - PID 6858 | |11;stanford-crypto-ccm;119;128;117;123;120;122;115;117;115;153 05:21:27 INFO - PID 6858 | |12;stanford-crypto-pbkdf2;163;162;167;163;162;176;166;163;163;168 05:21:27 INFO - PID 6858 | |13;stanford-crypto-sha256-iterative;64;74;61;61;63;66;61;60;62;61 05:21:27 INFO - PID 6858 | __end_tp_report 05:21:27 INFO - PID 6858 | __start_cc_report 05:21:27 INFO - PID 6858 | _x_x_mozilla_cycle_collect,840 05:21:27 INFO - PID 6858 | __end_cc_report 05:21:27 INFO - PID 6858 | __startTimestamp1501676487696__endTimestamp 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | ------- Summary: start ------- 05:21:27 INFO - PID 6858 | Number of tests: 14 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#0] ai-astar Cycles:10 Average:103.10 Median:107.50 stddev:4.51 (4.2%) stddev-sans-first:3.98 05:21:27 INFO - PID 6858 | Values: 96.0 106.0 102.0 106.0 106.0 105.0 107.0 98.0 97.0 108.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:141.00 stddev:21.04 (14.9%) stddev-sans-first:22.31 05:21:27 INFO - PID 6858 | Values: 136.0 111.0 141.0 172.0 115.0 147.0 141.0 127.0 170.0 120.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:3.33 (2.0%) stddev-sans-first:3.54 05:21:27 INFO - PID 6858 | Values: 164.0 168.0 162.0 162.0 161.0 164.0 163.0 161.0 167.0 171.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#3] audio-fft Cycles:10 Average:89.40 Median:76.50 stddev:28.88 (37.7%) stddev-sans-first:30.09 05:21:27 INFO - PID 6858 | Values: 74.0 75.0 76.0 168.0 80.0 79.0 77.0 91.0 100.0 74.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:78.50 stddev:23.65 (30.1%) stddev-sans-first:24.41 05:21:27 INFO - PID 6858 | Values: 79.0 79.0 129.0 89.0 78.0 78.0 124.0 78.0 79.0 132.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:115.00 stddev:1.83 (1.6%) stddev-sans-first:1.90 05:21:27 INFO - PID 6858 | Values: 114.0 115.0 115.0 114.0 115.0 114.0 114.0 120.0 114.0 115.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 05:21:27 INFO - PID 6858 | Values: 149.0 150.0 149.0 149.0 148.0 149.0 148.0 151.0 148.0 149.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:21:27 INFO - PID 6858 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 95.0 95.0 95.0 94.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#8] json-parse-financial Cycles:10 Average:49.30 Median:49.00 stddev:3.92 (8.0%) stddev-sans-first:4.15 05:21:27 INFO - PID 6858 | Values: 49.0 48.0 47.0 49.0 47.0 49.0 50.0 60.0 47.0 47.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#9] json-stringify-tinderbox Cycles:10 Average:54.50 Median:55.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 05:21:27 INFO - PID 6858 | Values: 55.0 55.0 55.0 54.0 54.0 54.0 55.0 54.0 54.0 55.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:74.50 stddev:2.42 (3.3%) stddev-sans-first:2.57 05:21:27 INFO - PID 6858 | Values: 75.0 73.0 74.0 73.0 81.0 77.0 74.0 74.0 74.0 76.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#11] stanford-crypto-ccm Cycles:10 Average:122.90 Median:121.00 stddev:11.31 (9.3%) stddev-sans-first:11.91 05:21:27 INFO - PID 6858 | Values: 119.0 128.0 117.0 123.0 120.0 122.0 115.0 117.0 115.0 153.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:164.50 stddev:4.32 (2.6%) stddev-sans-first:4.50 05:21:27 INFO - PID 6858 | Values: 163.0 162.0 167.0 163.0 162.0 176.0 166.0 163.0 163.0 168.0 05:21:27 INFO - PID 6858 | 05:21:27 INFO - PID 6858 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:62.50 stddev:4.16 (6.7%) stddev-sans-first:4.41 05:21:27 INFO - PID 6858 | Values: 64.0 74.0 61.0 61.0 63.0 66.0 61.0 60.0 62.0 61.0 05:21:27 INFO - PID 6858 | -------- Summary: end -------- 05:21:27 INFO - PID 6858 | 05:21:28 INFO - PID 6858 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 05:21:28 INFO - TEST-INFO | 6858: exit 0 05:21:28 INFO - TEST-OK | kraken | took 60372ms 05:21:28 INFO - SUITE-END | took 1170s 05:21:28 INFO - Completed test suite (00:19:30) 05:21:28 INFO - javascript benchmark 05:21:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8796.532668946593, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3389.3560223557524, 3394.1005633507125, 3404.1319463536793, 3427.794332340058, 3456.6197718250987, 6071.544634152434, 6144.631322120676, 6157.199564056672, 6228.781992630259, 6232.594881332716, 6019.969900150504, 6121.204243448346, 6157.722902469389, 6166.0, 6169.0, 5285.154375299952, 5298.7350632468315, 5396.298481197439, 5415.512603865652, 5452.04589196889, 5367.463253674621, 5421.918671219915, 5470.452954704538, 5496.230527726121, 5497.972510137463, 5281.656692315007, 5353.785848566053, 5357.81247656334, 5419.5393391561765, 5434.130539113762, 5350.919736203921, 5378.193271009341, 5429.619926605139, 5439.048166570834, 5445.0, 5257.263983042377, 5339.3058902342445, 5369.892602147975, 5426.538744206747, 5427.3215848019, 5989.730462129215, 6021.63870167787, 6023.337432882379, 6036.245469316335, 6060.666663333559, 6592.835179120512, 6623.105880706091, 6680.498962577826, 6702.430293425016, 6702.731890724365, 6734.360235777643, 6737.528373013891, 6795.762148324785, 6816.420604248645, 6835.965820170867, 6105.816825495198, 6188.535859810488, 6201.937980620226, 6207.596506227081, 6279.591826531261, 6583.9012414813815, 6680.465562754968, 6788.185417749902, 6801.795946121624, 6808.489363297674, 6538.738450462023, 6546.214454265519, 6582.275949645536, 6627.1716035495565, 6627.403533681992, 6650.83372915681, 6663.300353462913, 6694.062831203635, 6744.190697116377, 6761.695723692445, 6472.870542589122, 6589.341065893373, 6623.271440141581, 6653.63405012729, 6693.79918602433, 6658.201015878126, 6690.297518760557, 6692.464602831859, 6708.765193218312, 6733.989901514714, 4707.199776038082, 4722.338872557844, 4750.572448479654, 4777.784999675023, 4871.5128487151715, 4262.957370426256, 4270.316749320156, 4276.059266961263, 4279.593438623326, 4281.486221653359, 6189.257289125334, 6263.96868015657, 6280.0, 6337.746490140435, 6363.077353783676, 6564.638944857983, 6586.04502347157, 6628.96685516579, 6655.700493477805, 6712.261651218362, 6234.937650623436, 6289.465395441347, 6296.464800491918, 6325.4939604832425, 6327.209098862642, 5401.162819762943, 5464.87969966156, 5468.589855760754, 5474.2609747683555, 5478.041342765, 6816.556923799937, 6842.794716158423, 6887.449004079662, 6892.827679307957, 6921.0, 6469.45009674183, 6573.408393244631, 6654.933450665625, 6658.700358483783, 6674.365935236145, 6896.827579310558, 6915.239323674291, 6921.792346229621, 6984.685689143899, 7024.754133605299, 6502.0, 6535.215774107138, 6547.9672601636685, 6585.0, 6585.374389432996, 6593.142891424084, 6602.702878370389, 6696.5647232929705, 6707.631080290631, 6729.596224226562, 6397.7760778374395, 6434.967825160844, 6444.774432895014, 6458.354164583504, 6470.191226096738, 6480.967595161994, 6497.935020649733, 6524.2823289439075, 6552.312007239171, 6559.180102487189, 6481.287058423475, 6499.902501462388, 6532.8366790830605, 6539.4768418525455, 6556.016597510412, 6437.420632143129, 6487.675616219076, 6618.768343107968, 6627.37039981201, 6641.701123449359, 6259.874802503833, 6335.968320158554, 6343.080253363144, 6358.109864618865, 6403.199600049994], "value": 6040.945330408339, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [865.189033299273, 890.528020149321, 922.6125027488454, 946.659202687033, 952.2001518724275, 43109.137817243674, 44104.33843492346, 44148.77925610376, 44151.337729934, 44178.779106104506, 39461.0, 39769.40345894814, 39918.80040599793, 40150.59849401505, 40227.79886100566, 6733.46132309414, 6802.727890884359, 6844.589324640538, 6846.281140480252, 6906.0, 6765.0, 6915.1010368652005, 6920.515563910529, 6921.896171557405, 6933.375996160345, 7506.774796756106, 7665.156832748393, 7689.384849212078, 7690.192529784376, 7693.49992250502, 7483.738069167553, 7599.506032107895, 7643.350315223212, 7657.540547567136, 7669.6932122715025, 7491.550506969599, 7534.510256833289, 7536.8115797104965, 7546.132194797558, 7554.282343177444, 40450.797746011376, 40717.389239161435, 40818.183636327405, 40846.79576602128, 40870.0, 3486.8430920608635, 3516.876909308162, 3520.2431477232517, 3520.6655367740022, 3523.066387407339, 31820.20449488758, 31911.202219944455, 31922.361552769045, 31943.0, 31957.201069973205, 4985.5513003829465, 5003.0, 5036.471170527078, 5039.118154323016, 5058.215976523645, 228463.0, 228546.0, 229366.0, 229705.0, 229741.0, 54287.0, 54354.184687229725, 54376.72811635916, 54428.183577246375, 54587.18119228215, 57110.42889571134, 57383.0, 57416.13875791866, 57515.0, 57544.71227643835, 15545.378184872705, 15708.450204242796, 15709.607259818367, 15781.21093945321, 15800.60498487547, 12546.0, 12561.811572826415, 12563.93718031404, 12568.685782855318, 12573.497060117676, 15419.383224671112, 15459.227038648247, 15479.993800402937, 15531.922340388226, 15630.06219626826, 5856.560757943172, 6075.0, 6085.269767627913, 6102.877942441215, 6137.631742095488, 1091.4870011094772, 1093.1965005720788, 1095.386583513235, 1101.3832253937821, 1104.2104895000114, 24587.0, 24653.137140200008, 24660.0, 24731.62902556463, 24852.37869053288, 1187.9524819007315, 1195.0977012355615, 1197.3055627735891, 1197.6287350921243, 1206.4631239098517, 12922.966162706962, 12933.805992910115, 13085.869141308656, 13130.540431084866, 13268.601941941757, 282723.0, 283196.0, 283760.0, 283953.0, 284011.0, 56755.43244567584, 56837.0, 56853.0, 56917.0, 61766.0, 1512.6369671278815, 1537.2006556590734, 1542.537238828347, 1543.4597890738153, 1557.2992153530956, 29598.704012960457, 29729.25676858096, 29803.254918627208, 30327.848360758057, 30466.0, 39569.0, 39601.0, 39612.207755845295, 39654.0, 39698.80150599228, 231926.0, 233664.0, 233870.0, 234316.0, 234717.0, 33973.49039764455, 34095.31809363749, 34176.82911585426, 36758.63241367552, 37138.62861371352, 47703.76148119237, 49337.50662493329, 49419.0, 49705.0, 49893.25160122674, 35381.0, 36458.63541364553, 36490.8175459121, 36493.635063649024, 36596.45105323369, 35810.28379432345, 35945.281094378486, 36321.27357452783, 36498.0, 36546.8172659135, 40423.0, 40572.594274058065, 40625.79687101664, 40673.79663101665, 41233.0, 34349.0, 34349.656503435646, 34445.31109377849, 34590.0, 35037.649623503436, 26282.73717262803, 27082.32294192661, 27107.59338609883, 27116.32209194786, 27548.449031018867], "value": 18260.87711565971, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [577.4831525784423, 588.5938702295413, 593.1103344982529, 594.6818452128109, 603.2519675602254, 22655.320340389775, 23249.418764530852, 23528.411789705267, 23633.999999999978, 23636.5272694546, 27337.0, 27599.0, 27795.444091118166, 27845.721542784566, 27868.16395508138, 6166.599171053878, 6300.432961033506, 6302.369763023707, 6303.968480157593, 6313.842153946142, 5818.4181581841685, 6316.178896743417, 6326.873462530747, 6331.810045698613, 6361.4274715275615, 6330.525210609199, 6332.0, 6343.968280158616, 6346.809595712136, 6351.079093531435, 6327.96836015817, 6330.65181415022, 6340.746370145189, 6341.080543321239, 6342.746290148411, 5829.300483941912, 5861.765529378819, 5869.060950247983, 5869.8239052828485, 5885.382034886318, 29729.25676858074, 30874.536881946788, 30923.690763092305, 31054.0, 31320.686793132234, 2233.196049422223, 2274.8521346112616, 2275.0672224388086, 2277.851939623936, 2278.3620586235716, 19560.31538896146, 20224.0, 20318.898405508025, 20360.0, 20371.694424583642, 2323.581755284048, 2331.079223706626, 2363.0557362297745, 2400.543896659629, 2448.951020979588, 158157.0, 165932.0, 166055.1697241518, 166348.0, 166544.99999999878, 15675.372985080468, 16089.678206435805, 16350.264238109314, 16385.033795944564, 16427.342906283855, 23970.88014559951, 24048.15831445891, 24090.51818963646, 24092.0, 24484.755152448248, 10064.194864410829, 10071.51102649058, 10093.848592271122, 10318.793624127626, 10338.793224135476, 8368.58157092143, 9121.863172052424, 9217.953910230406, 9231.123043311009, 9273.675421360354, 8897.154770296811, 9854.556544955494, 9915.355501892498, 9964.10323070927, 10096.394216347042, 2740.7122252163235, 2754.986225068862, 2793.7625301849166, 2818.844963527026, 2892.262473069396, 1057.7567159553346, 1067.2742535076068, 1076.6985244131656, 1084.1326938449367, 1085.8859819718973, 21830.781692182874, 21891.890540547196, 22050.558988820132, 22050.779492205194, 22264.443388915406, 1119.960801371948, 1127.3461392392392, 1131.9434028298551, 1134.6199023327113, 1141.08713029576, 12033.458494367775, 12172.695682608006, 12301.815472767916, 12353.691157720948, 12362.196457230431, 569247.0, 571562.0, 575258.0, 591721.0, 601403.0, 136042.0, 141536.0, 142763.0, 144618.0, 149904.0, 1314.2114731161228, 1321.4648067532664, 1358.959231223065, 1363.2979015806868, 1367.7538043152329, 22324.77675223227, 22928.19751308763, 23118.1908633197, 23126.653100203846, 23237.070517179123, 60388.698056509435, 62913.68543157255, 63106.36893631005, 65030.34969650243, 65136.674316628116, 81795.0, 83231.16768832477, 83265.0, 84258.57870710608, 84329.0, 13107.0, 13505.054646175067, 13547.79678304846, 14895.404183832525, 15288.159151246344, 24680.876595617625, 25594.360140996625, 25599.488010240064, 25682.0, 25720.871395642902, 20052.799472005678, 20304.492387690425, 20429.897850510653, 20487.078081486667, 20529.89735051315, 16308.103054332125, 16953.321867125178, 17124.0, 17125.48623541296, 17437.564060898578, 20708.896455517624, 20778.376648700563, 20835.166593336704, 20849.270275540282, 20908.895455522626, 14679.266036698336, 14796.112233266038, 14880.7767883484, 14935.626609334418, 14953.102813831205, 20114.19543218255, 20135.798642013393, 20232.89883550573, 20411.79588204099, 20429.08069136863], "value": 12766.156337132792, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.8838998685112, 1395.2117053864565, 1409.1545072956224, 1434.4047220403534, 1436.5977526292643, 15026.549203523899, 15182.316795744191, 15464.92267538662, 15477.535673929786, 15676.294566744495, 15754.527364179094, 16009.83990160098, 16028.839711602881, 16029.038257704546, 16032.679346413093, 2666.8666566671686, 2715.891364345424, 2716.2666080158347, 2717.184844546628, 2717.9728202717915, 2627.5927231279184, 2640.366312085095, 2676.571748520237, 2727.9318017049536, 2728.058819707198, 2554.693436787588, 2557.6802899637546, 2573.5496288149593, 2577.5231582157267, 2581.7676409123173, 2608.8565128917903, 2650.2446802661248, 2659.6143559184, 2661.3745769744096, 2663.507251158532, 2614.647022651937, 2633.0652618320605, 2634.1570697376847, 2643.0, 2645.2857728413414, 14887.702245955128, 15236.847631523653, 15375.538733838002, 15377.692446151013, 15395.230238488142, 7567.394608431312, 7781.144074151839, 7783.532988020737, 7785.299323060895, 7787.260210280015, 5203.19350500673, 5370.946290537084, 5410.864728381783, 5422.783088676449, 5466.590005749585, 1908.1413363986262, 1933.729277901095, 1962.9010711662506, 1988.4929343017582, 2009.437357539891, 26223.475530489282, 26292.342691432867, 26327.868360658267, 26479.47041059187, 26515.867420662773, 6796.558223715541, 6951.756688515979, 6995.300469952964, 7092.787216383516, 7155.964220178866, 17972.640547189243, 18098.90950545265, 18113.909430453026, 18132.546686332946, 18136.274549018155, 8105.959470202729, 8322.833543329221, 8374.916250837536, 8377.078521362646, 8380.287675547646, 7471.8505629887095, 7605.087389513294, 7651.196624354363, 7663.042119735033, 7729.729459468892, 4990.30135780991, 5251.2123181523075, 5353.678779273256, 5428.104362780097, 5445.346558413015, 1455.294182321573, 1563.5309407177801, 1566.6083479130218, 1605.8875878688614, 1608.6460978584694, 883.1257055514994, 900.4687234531658, 900.819836032796, 909.5406819556082, 914.7164379042518, 8050.758477245692, 8287.8342433151, 8302.294304984023, 8313.875291870627, 8448.83102337962, 997.7356000659831, 1000.9199264058858, 1004.3973615830589, 1015.918726501878, 1017.2014968249888, 4921.483244259372, 5201.349831271091, 5355.223492593552, 5366.356037275552, 5408.269883565668, 20862.0, 21212.57574848525, 21473.14107435716, 21473.785262147492, 21948.780512194993, 21449.356519304445, 21779.67330490044, 22086.11655533793, 22098.0, 22424.327270181922, 11513.481893314822, 11526.654200373834, 11809.704757381134, 11860.999999999827, 11887.940560297144, 8363.916360836558, 8507.319414446953, 8582.828343432971, 8633.438826476258, 8640.35197360213, 19280.325188618892, 19281.710774338117, 19439.12523936398, 19460.805391945898, 19883.0, 19773.70339444938, 19808.70286945726, 19868.503287417352, 19897.900510497355, 19952.900235498728, 12841.35793210317, 13190.406431710788, 13192.934035329761, 13208.27354495512, 13220.933895330461, 13117.475300987853, 14380.784288235476, 14522.0, 14620.049696769676, 14740.852591474377, 10801.0, 11516.712082198012, 11721.88278117242, 11888.821667674818, 12072.75854482923, 11028.779424411627, 11082.279651822606, 11084.667459976214, 11093.667189984313, 11347.54609815598, 9591.472469014272, 9808.705738827846, 9821.263405244777, 9933.255005874444, 9936.056074672895, 7272.454565907472, 7368.189499155196, 7374.078240219973, 7432.554046757212, 7489.737859174903, 7350.411967042731, 7396.778096657109, 7420.480566360519, 7441.888371674321, 7536.1333446654335], "value": 6796.817583621648, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.25610839848616, 510.22955337440476, 531.2721571447116, 534.8475684429939, 536.8657835541114, 6028.306744724358, 6073.392660733935, 6115.449609535141, 6123.3876612338745, 6147.323794382625, 5811.099279611667, 5960.672163031031, 5963.194968679238, 6043.425874541911, 6044.879102417949, 4639.141758774637, 4705.364775755272, 4725.220338644137, 4746.881327966794, 4747.928781068286, 4605.815767369301, 4677.789499472515, 4684.05611671156, 4704.0356726870905, 4725.362076119732, 4658.114958157956, 4663.347131401607, 4667.953320466786, 4675.906481870361, 4704.670673052888, 4527.750973696446, 4612.976935115336, 4659.4175728034, 4676.321933319684, 4684.5549672781035, 4265.7014009019385, 4282.122164956177, 4283.372318518943, 4300.1399720056115, 4306.224879521685, 5943.554233432466, 5985.25184351956, 6006.879862402771, 6008.909866352008, 6010.549208809357, 5596.804111856106, 5659.330497503246, 5660.235868157787, 5699.316082070178, 5736.110902810071, 6435.549511534195, 6455.451286640641, 6456.580322279036, 6471.385218404244, 6474.579152355129, 4857.295692124622, 4868.904496488297, 4876.878078048042, 4877.634177436671, 4902.362692849907, 6691.76578819739, 6723.495737819683, 6729.798106056826, 6733.730650773914, 6783.3555812197765, 4974.228994505857, 4979.30289759434, 5002.524760147853, 5017.569992552105, 5044.1677123274985, 5087.720175390389, 5096.286519887219, 5108.9744551277745, 5109.642325037212, 5139.383274007143, 5684.914726279109, 5686.175504551817, 5697.914531282034, 5726.599138060379, 5736.827895163152, 6397.296297407282, 6448.9355106449275, 6454.709058188381, 6465.3857883501, 6507.446867016357, 1663.1601041473978, 1702.8297170282872, 1718.4157386488405, 1738.078818226342, 1743.5553933746814, 1211.1461419699092, 1216.4586758892385, 1218.5369559567307, 1232.3037483821613, 1239.3431481314917, 6156.6921653917125, 6191.597546159485, 6212.47193988516, 6233.688315584202, 6248.250209974741, 6339.873202535923, 6473.158489396443, 6482.67586620667, 6573.5677799213645, 6671.899921501182, 6185.721642526097, 6192.783252586228, 6193.142057382857, 6220.191375121205, 6320.905186422207, 3436.914077148041, 3481.1993241554583, 3490.1798077452286, 3504.404251277289, 3532.5339983906906, 6877.89683154753, 6890.689918953659, 6938.930610693828, 6939.20199177091, 7006.229314775371, 6770.984352347187, 6820.0, 6830.624315662686, 6841.255479852605, 6844.1444819397575, 6926.9307306926285, 6943.132108486439, 6956.686949087303, 6970.059042029261, 6970.512064155562, 6567.934320656732, 6589.604623722592, 6645.767398141042, 6649.833754156185, 6668.499862510428, 6578.276389597237, 6587.934120658732, 6611.330711455189, 6616.2060552732055, 6645.568038077509, 6856.280090590616, 6894.620795856276, 6917.723291068301, 6937.231006093414, 6970.477214208853, 4959.677620954771, 4982.451930287593, 5008.173651347487, 5008.373953255843, 5037.496250374933, 6407.967960160169, 6565.770198043237, 6633.124653379666, 6666.400023997863, 6682.766103186365, 6169.784057557964, 6260.436560709558, 6322.525810564134, 6426.29310775805, 6451.3548645135115, 6339.461145802658, 6347.143135676624, 6355.841103972438, 6416.550841441054, 6436.839079022875, 6178.351273116258, 6289.0, 6376.713047912763, 6377.107204991212, 6397.0404439332615, 6311.968440157953, 6380.617162970237, 6386.828478317394, 6412.6793660317735, 6439.517036222208, 6220.2535695715, 6249.37536228977, 6277.591956522811, 6285.214348206474, 6324.6521441321165], "value": 5015.704177470398, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [922.5986695787333, 928.2713070239861, 944.8299306124903, 953.5994882149497, 957.7988622389296, 18045.63908721825, 18749.34377296795, 18791.154398052087, 18815.153318100685, 22524.211652592163, 20737.274195403163, 21475.140994360245, 21549.56900861982, 21560.784392156074, 21620.8918955405, 996.7358649957785, 1022.3201570955306, 1025.0467065628961, 1027.0345874877628, 1027.1782573940854, 1012.7265638277661, 1082.2532452607675, 1083.312096818522, 1083.5178345636195, 1084.3114622214912, 1118.7091356247354, 1123.6516679829238, 1124.0557931337676, 1126.3748619516207, 1127.6391554702498, 1094.4691824465208, 1095.7534554725203, 1098.6154845803985, 1100.070440477799, 1109.0295991007868, 804.7022601637373, 810.2748040503716, 811.0915774332726, 819.2831272636444, 820.6717313074759, 16626.50120496387, 16645.25096370666, 16700.164991750364, 16729.330826766916, 19691.11389987454, 18582.256709731595, 18951.33670321546, 18987.0, 18999.145038473303, 19506.80493195064, 16083.839161608352, 16489.752653710202, 16637.4176903809, 16786.328546858113, 16790.16049197535, 3652.853885844563, 3652.9223878955772, 3656.1042544576485, 3656.4149736042104, 3660.7071434285194, 137210.0, 141633.2918335412, 142173.0, 142920.99999999895, 143778.0, 16882.74675879863, 17061.829381706273, 17295.135243237786, 17456.563585910455, 17546.122693865254, 31944.201394964846, 32496.83751581227, 32983.0, 33263.004219746406, 33298.66701333004, 7548.509346892434, 7925.8811117833275, 7957.920420795834, 8315.0, 9133.086691330815, 4286.035641980592, 4376.387305777193, 4400.75795831226, 4418.469783625986, 4428.092241090569, 5322.33470816148, 5361.195820626859, 5386.48828361305, 5434.076207044812, 5438.619296649196, 1050.1808589300358, 1077.6443773554709, 1078.5092782783815, 1092.983605245922, 1093.2074246171621, 723.4176487927164, 736.6095969136367, 745.5712965045012, 752.834376437183, 757.1293013034943, 12005.819912701316, 12847.743045139232, 13052.869471305165, 13358.732825343439, 13377.197368157747, 701.214639603637, 711.6086152616041, 713.6039498078616, 719.0616245799177, 726.0488759724777, 5757.510611598061, 6713.664316784141, 6851.520393572402, 7010.298970103051, 7059.470539709542, 123078.38460807818, 123690.38154809168, 127546.0, 129081.35459322644, 132878.0, 108458.0, 110265.0, 111950.44024779987, 111996.0, 112046.0, 98127.0, 100359.49820250852, 102521.9999999985, 102760.0, 107252.0, 13420.798688019493, 13703.109297895606, 13759.380827862971, 13902.582922512342, 13972.371243293874, 46267.53732462767, 48058.75970620124, 48119.0, 48733.5126648729, 49716.0, 60974.0, 63145.68427157835, 63280.36719632745, 63336.36663633305, 64271.35728642841, 18627.441176764718, 19091.522711931757, 19322.710159347338, 19352.0, 19370.903145484182, 22010.669839952094, 22473.0, 22485.21301754496, 22515.0, 22731.65902511497, 19390.903045484683, 19745.802541974397, 19927.0, 19952.800471995673, 19954.70067949011, 20465.283715070498, 20581.176752929714, 20673.379798606067, 20723.0, 20762.584748305253, 18292.542686432946, 18393.54016149607, 18493.352732654294, 18497.81502184961, 18579.721304180177, 14183.432662693376, 14285.28573571338, 14290.999630025799, 14358.641033974234, 14702.0, 14888.62778430548, 14967.251637417869, 15129.319180636678, 15139.697206056038, 15337.309821057856], "value": 9649.747360060868, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 106.0, 102.0, 106.0, 106.0, 105.0, 107.0, 98.0, 97.0, 108.0], "value": 103.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 111.0, 141.0, 172.0, 115.0, 147.0, 141.0, 127.0, 170.0, 120.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 162.0, 162.0, 161.0, 164.0, 163.0, 161.0, 167.0, 171.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 168.0, 80.0, 79.0, 77.0, 91.0, 100.0, 74.0], "value": 89.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 129.0, 89.0, 78.0, 78.0, 124.0, 78.0, 79.0, 132.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 115.0, 114.0, 114.0, 120.0, 114.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 149.0, 148.0, 149.0, 148.0, 151.0, 148.0, 149.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 47.0, 49.0, 47.0, 49.0, 50.0, 60.0, 47.0, 47.0], "value": 49.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 73.0, 81.0, 77.0, 74.0, 74.0, 74.0, 76.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 128.0, 117.0, 123.0, 120.0, 122.0, 115.0, 117.0, 115.0, 153.0], "value": 122.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 167.0, 163.0, 162.0, 176.0, 166.0, 163.0, 163.0, 168.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 61.0, 61.0, 63.0, 66.0, 61.0, 60.0, 62.0, 61.0], "value": 63.3, "unit": "score"}]}]} 05:21:28 INFO - Return code: 0 05:21:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:21:28 INFO - # TBPL SUCCESS # 05:21:28 INFO - Running post-action listener: _package_coverage_data 05:21:28 INFO - Running post-action listener: _resource_record_post_action 05:21:28 INFO - [mozharness: 2017-08-02 12:21:28.514004Z] Finished run-tests step (success) 05:21:28 INFO - Running post-run listener: _resource_record_post_run 05:21:28 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 12288; Write bytes: 317571072; Read time: 108; Write time: 162484 05:21:28 INFO - TinderboxPrint: CPU usage
13.1% 05:21:28 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 108 05:21:28 INFO - TinderboxPrint: I/O write bytes / time
317,571,072 / 162,484 05:21:28 INFO - TinderboxPrint: CPU idle
8,235.5 (86.9%) 05:21:28 INFO - TinderboxPrint: CPU user
1,190.1 (12.6%) 05:21:28 INFO - TinderboxPrint: Swap in / out
0 / 0 05:21:28 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 6901760; Read time: 0; Write time: 4040 05:21:28 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:21:28 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 8192; Write bytes: 310669312; Read time: 96; Write time: 158444 05:21:29 INFO - Running post-run listener: _upload_blobber_files 05:21:29 INFO - Blob upload gear active. 05:21:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:21:29 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:21:29 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:21:29 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:21:29 INFO - (blobuploader) - INFO - Open directory for files ... 05:21:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:21:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:21:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:30 INFO - (blobuploader) - INFO - Done attempting. 05:21:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:21:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:21:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:36 INFO - (blobuploader) - INFO - Done attempting. 05:21:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:21:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:21:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:37 INFO - (blobuploader) - INFO - Done attempting. 05:21:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:21:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:21:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:21:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:21:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:21:38 INFO - (blobuploader) - INFO - Done attempting. 05:21:38 INFO - (blobuploader) - INFO - Iteration through files over. 05:21:38 INFO - Return code: 0 05:21:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:21:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:21:38 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb9558ee6997933831f561eaaeb56e3bd8fe6da5a9f3d1fde4c1f54537d79b4302ac77771bc121ca4db877a3b385305503e27958a0b02175c510549852a635ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0adb334555ed5edf8b6061389832e31d26ad1506fd7efe24c02805267f6b86dddd96f96a16a2f00d476b17dc9aeaae8cf7317a71d87921d07661e20857e6831e", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7066960f344dd0e5039f0a761b8abaf010a040e336cbde9d8c2417f582f00848a175b548cfe51321dcc33439c936cd3e5bcf6b717923b5d184257bebd2249801", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c968506277304f2b1264835304757cd4d83422919803ce0433f4646bb5c25a5fc4125edf4e2f8602a6209830b204ede5dd0d36070f70d4d64b976253365017"} 05:21:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:21:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:21:38 INFO - Contents: 05:21:38 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb9558ee6997933831f561eaaeb56e3bd8fe6da5a9f3d1fde4c1f54537d79b4302ac77771bc121ca4db877a3b385305503e27958a0b02175c510549852a635ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0adb334555ed5edf8b6061389832e31d26ad1506fd7efe24c02805267f6b86dddd96f96a16a2f00d476b17dc9aeaae8cf7317a71d87921d07661e20857e6831e", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7066960f344dd0e5039f0a761b8abaf010a040e336cbde9d8c2417f582f00848a175b548cfe51321dcc33439c936cd3e5bcf6b717923b5d184257bebd2249801", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c968506277304f2b1264835304757cd4d83422919803ce0433f4646bb5c25a5fc4125edf4e2f8602a6209830b204ede5dd0d36070f70d4d64b976253365017"} 05:21:38 INFO - Running post-run listener: copy_logs_to_upload_dir 05:21:38 INFO - Copying logs to upload dir... 05:21:38 INFO - mkdir: /builds/slave/test/build/upload/logs 05:21:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.515527 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-08-02 05:21:38.089138) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 05:21:38.092871) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb9558ee6997933831f561eaaeb56e3bd8fe6da5a9f3d1fde4c1f54537d79b4302ac77771bc121ca4db877a3b385305503e27958a0b02175c510549852a635ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0adb334555ed5edf8b6061389832e31d26ad1506fd7efe24c02805267f6b86dddd96f96a16a2f00d476b17dc9aeaae8cf7317a71d87921d07661e20857e6831e", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7066960f344dd0e5039f0a761b8abaf010a040e336cbde9d8c2417f582f00848a175b548cfe51321dcc33439c936cd3e5bcf6b717923b5d184257bebd2249801", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c968506277304f2b1264835304757cd4d83422919803ce0433f4646bb5c25a5fc4125edf4e2f8602a6209830b204ede5dd0d36070f70d4d64b976253365017"} build_url:https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://queue.taskcluster.net/v1/task/JoHvQIT-QwS1695eRq3-mg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb9558ee6997933831f561eaaeb56e3bd8fe6da5a9f3d1fde4c1f54537d79b4302ac77771bc121ca4db877a3b385305503e27958a0b02175c510549852a635ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0adb334555ed5edf8b6061389832e31d26ad1506fd7efe24c02805267f6b86dddd96f96a16a2f00d476b17dc9aeaae8cf7317a71d87921d07661e20857e6831e", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7066960f344dd0e5039f0a761b8abaf010a040e336cbde9d8c2417f582f00848a175b548cfe51321dcc33439c936cd3e5bcf6b717923b5d184257bebd2249801", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8c968506277304f2b1264835304757cd4d83422919803ce0433f4646bb5c25a5fc4125edf4e2f8602a6209830b204ede5dd0d36070f70d4d64b976253365017"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 05:21:38.117739) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 05:21:38.118114) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1501658937.256902-1442670958 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004357 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 05:21:38.201667) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 05:21:38.201994) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 05:21:38.202319) ========= ========= Total master_lag: 0.31 =========