builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-051 starttime: 1501723322.9 results: success (0) revision: 6f1914a4f241b8ac62953de069296397b7645cd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:02.898983) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:02.899468) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:02.899945) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:02.960381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:02.960688) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-02 18:22:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.4M=0s 2017-08-02 18:22:03 (50.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131900 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:03.135745) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:03.139217) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019686 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:03.221477) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 18:22:03.221924) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6f1914a4f241b8ac62953de069296397b7645cd1 --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 6f1914a4f241b8ac62953de069296397b7645cd1 --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=5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-02 18:22:03,523 truncating revision to first 12 chars 2017-08-02 18:22:03,523 Setting DEBUG logging. 2017-08-02 18:22:03,523 attempt 1/10 2017-08-02 18:22:03,523 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6f1914a4f241?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-02 18:22:04,712 unpacking tar archive at: mozilla-central-6f1914a4f241/testing/mozharness/ program finished with exit code 0 elapsedTime=1.528557 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-08-02 18:22:04.779543) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:04.779946) ========= script_repo_revision: 6f1914a4f241b8ac62953de069296397b7645cd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:04.780422) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:04.780699) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-02 18:22:04.798941) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-02 18:22:04.802441) ========= /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=5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:22:05 INFO - MultiFileLogger online at 20170802 18:22:05 in /builds/slave/test 18:22:05 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 18:22:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:22:05 INFO - {'append_to_log': False, 18:22:05 INFO - 'base_work_dir': '/builds/slave/test', 18:22:05 INFO - 'blob_upload_branch': 'Firefox', 18:22:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:22:05 INFO - 'branch': 'Firefox', 18:22:05 INFO - 'buildbot_json_path': 'buildprops.json', 18:22:05 INFO - 'code_coverage': False, 18:22:05 INFO - 'config_files': ('talos/linux_config.py',), 18:22:05 INFO - 'default_actions': ('clobber', 18:22:05 INFO - 'read-buildbot-config', 18:22:05 INFO - 'download-and-extract', 18:22:05 INFO - 'populate-webroot', 18:22:05 INFO - 'create-virtualenv', 18:22:05 INFO - 'install', 18:22:05 INFO - 'setup-mitmproxy', 18:22:05 INFO - 'run-tests'), 18:22:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:22:05 INFO - 'disable_ccov_upload': False, 18:22:05 INFO - 'download_minidump_stackwalk': True, 18:22:05 INFO - 'download_symbols': 'ondemand', 18:22:05 INFO - 'enable_stylo': False, 18:22:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:22:05 INFO - 'tooltool.py': '/tools/tooltool.py', 18:22:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:22:05 INFO - '/tools/misc-python/virtualenv.py')}, 18:22:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:22:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:22:05 INFO - 'gecko_profile': False, 18:22:05 INFO - 'gecko_profile_interval': 0, 18:22:05 INFO - 'installer_path': 'installer.exe', 18:22:05 INFO - 'log_level': 'info', 18:22:05 INFO - 'log_name': 'talos', 18:22:05 INFO - 'log_to_console': True, 18:22:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:22:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:22:05 INFO - 'opt_config_files': (), 18:22:05 INFO - 'pip_index': False, 18:22:05 INFO - 'suite': 'dromaeojs-e10s', 18:22:05 INFO - 'system_bits': '32', 18:22:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:22:05 INFO - 'title': 'talos-linux64-ix-051', 18:22:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:22:05 INFO - 'use_talos_json': True, 18:22:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:22:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:22:05 INFO - 'work_dir': 'build'} 18:22:05 INFO - [mozharness: 2017-08-03 01:22:05.202544Z] Running clobber step. 18:22:05 INFO - Running pre-action listener: _resource_record_pre_action 18:22:05 INFO - Running main action method: clobber 18:22:05 INFO - rmtree: /builds/slave/test/build 18:22:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:22:06 INFO - Running post-action listener: _resource_record_post_action 18:22:06 INFO - [mozharness: 2017-08-03 01:22:06.756557Z] Finished clobber step (success) 18:22:06 INFO - [mozharness: 2017-08-03 01:22:06.756659Z] Running read-buildbot-config step. 18:22:06 INFO - Running pre-action listener: _resource_record_pre_action 18:22:06 INFO - Running main action method: read_buildbot_config 18:22:06 INFO - Using buildbot properties: 18:22:06 INFO - { 18:22:06 INFO - "project": "", 18:22:06 INFO - "product": "firefox", 18:22:06 INFO - "who": "kwierso@gmail.com", 18:22:06 INFO - "installer_path": "public/build/target.tar.bz2", 18:22:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 18:22:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:22:06 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 18:22:06 INFO - "stage_platform": "linux64-stylo", 18:22:06 INFO - "basedir": "/builds/slave/test", 18:22:06 INFO - "buildnumber": 13, 18:22:06 INFO - "platform": "ubuntu64_hw_stylo", 18:22:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:22:06 INFO - "slavebuilddir": "test", 18:22:06 INFO - "taskId": "a7wDkdv4Q_CfCX9iRQQjaQ", 18:22:06 INFO - "branch": "mozilla-central", 18:22:06 INFO - "script_repo_revision": "production", 18:22:06 INFO - "revision": "6f1914a4f241b8ac62953de069296397b7645cd1", 18:22:06 INFO - "slavename": "talos-linux64-ix-051", 18:22:06 INFO - "repo_path": "mozilla-central" 18:22:06 INFO - } 18:22:06 INFO - Finding installer, test and symbols from parent task. 18:22:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a7wDkdv4Q_CfCX9iRQQjaQ'}, attempt #1 18:22:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a7wDkdv4Q_CfCX9iRQQjaQ'}, attempt #1 18:22:07 INFO - Task dependencies: RZ_IcetKSemmvmdM-9uhXQ 18:22:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ'}, attempt #1 18:22:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 18:22:08 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.test_packages.json 18:22:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.crashreporter-symbols.zip 18:22:08 INFO - Running post-action listener: _resource_record_post_action 18:22:08 INFO - [mozharness: 2017-08-03 01:22:08.128255Z] Finished read-buildbot-config step (success) 18:22:08 INFO - [mozharness: 2017-08-03 01:22:08.128569Z] Running download-and-extract step. 18:22:08 INFO - Running pre-action listener: _resource_record_pre_action 18:22:08 INFO - Running main action method: download_and_extract 18:22:08 INFO - mkdir: /builds/slave/test/build/tests 18:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:08 INFO - trying https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.test_packages.json 18:22:08 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:22:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:22:08 INFO - Downloaded 989 bytes. 18:22:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:22:08 INFO - Using the following test package requirements: 18:22:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:22:08 INFO - u'common': [u'target.common.tests.zip'], 18:22:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:22:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:22:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:22:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:22:08 INFO - u'mozbase': [u'target.common.tests.zip'], 18:22:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:22:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:22:08 INFO - u'web-platform': [u'target.common.tests.zip', 18:22:08 INFO - u'target.web-platform.tests.tar.gz'], 18:22:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:22:08 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:22:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip 18:22:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:22:08 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip into memory 18:22:09 INFO - Content-Length response header: 54278304 18:22:09 INFO - Bytes received: 54278304 18:22:20 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:22:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip 18:22:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:22:20 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.common.tests.zip into memory 18:22:22 INFO - Content-Length response header: 54278304 18:22:22 INFO - Bytes received: 54278304 18:22:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.talos.tests.zip 18:22:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:22:35 INFO - Fetch https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.talos.tests.zip into memory 18:22:36 INFO - Content-Length response header: 14496464 18:22:36 INFO - Bytes received: 14496464 18:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:37 INFO - trying https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 18:22:37 INFO - Downloading https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:22:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:22:38 INFO - Downloaded 62233195 bytes. 18:22:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 18:22:38 INFO - mkdir: /builds/slave/test/properties 18:22:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:22:38 INFO - Writing to file /builds/slave/test/properties/build_url 18:22:38 INFO - Contents: 18:22:38 INFO - build_url:https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 18:22:38 INFO - Running post-action listener: _resource_record_post_action 18:22:38 INFO - Running post-action listener: set_extra_try_arguments 18:22:38 INFO - [mozharness: 2017-08-03 01:22:38.290341Z] Finished download-and-extract step (success) 18:22:38 INFO - [mozharness: 2017-08-03 01:22:38.290439Z] Running populate-webroot step. 18:22:38 INFO - Running pre-action listener: _resource_record_pre_action 18:22:38 INFO - Running main action method: populate_webroot 18:22:38 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'tests': ['damp', 'tps']}, 18:22:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:22:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:22:38 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:38 INFO - 'ts_paint', 18:22:38 INFO - 'tpaint', 18:22:38 INFO - 'sessionrestore', 18:22:38 INFO - 'sessionrestore_many_windows', 18:22:38 INFO - 'sessionrestore_no_auto_restore', 18:22:38 INFO - 'tabpaint']}, 18:22:38 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:22:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:22:38 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:22:38 INFO - 'talos_options': ['--mitmproxy', 18:22:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:22:38 INFO - '--firstNonBlankPaint'], 18:22:38 INFO - 'tests': ['quantum_pageload_google', 18:22:38 INFO - 'quantum_pageload_youtube', 18:22:38 INFO - 'quantum_pageload_amazon', 18:22:38 INFO - 'quantum_pageload_facebook']}, 18:22:38 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:22:38 INFO - 'talos_options': ['--stylo', 18:22:38 INFO - '--mitmproxy', 18:22:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:22:38 INFO - '--firstNonBlankPaint'], 18:22:38 INFO - 'tests': ['quantum_pageload_google', 18:22:38 INFO - 'quantum_pageload_youtube', 18:22:38 INFO - 'quantum_pageload_amazon', 18:22:38 INFO - 'quantum_pageload_facebook']}, 18:22:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:38 INFO - 'tsvgr_opacity', 18:22:38 INFO - 'tart', 18:22:38 INFO - 'tscrollx', 18:22:38 INFO - 'cart', 18:22:38 INFO - 'tsvg_static']}, 18:22:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:22:38 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:22:38 INFO - 'talos_options': ['--stylo', 18:22:38 INFO - '--stylo-threads=1', 18:22:38 INFO - '--mitmproxy', 18:22:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:22:38 INFO - '--firstNonBlankPaint'], 18:22:38 INFO - 'tests': ['tp6_google', 18:22:38 INFO - 'tp6_youtube', 18:22:38 INFO - 'tp6_amazon', 18:22:38 INFO - 'tp6_facebook']}, 18:22:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:22:38 INFO - 'talos_options': ['--xperf_path', 18:22:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:38 INFO - 'tests': ['tp5n']}}} 18:22:38 INFO - Running post-action listener: _resource_record_post_action 18:22:38 INFO - [mozharness: 2017-08-03 01:22:38.299068Z] Finished populate-webroot step (success) 18:22:38 INFO - [mozharness: 2017-08-03 01:22:38.299159Z] Running create-virtualenv step. 18:22:38 INFO - Running pre-action listener: _resource_record_pre_action 18:22:38 INFO - Running main action method: create_virtualenv 18:22:38 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22:38 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 18:22:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:22:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:38 INFO - Using real prefix '/usr' 18:22:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:38 INFO - Installing distribute.............................................................................................................................................................................................done. 18:22:40 INFO - Installing pip.................done. 18:22:40 INFO - Return code: 0 18:22:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:22:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:22:41 INFO - Reading from file tmpfile_stdout 18:22:41 INFO - Output received: 18:22:41 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) 18:22:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:22:41 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')]} 18:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:41 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')]} 18:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:41 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 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:41 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 18:22:41 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 18:22:41 INFO - Using env: {'DISPLAY': ':0', 18:22:41 INFO - 'HOME': '/home/cltbld', 18:22:41 INFO - 'LANG': 'en_US.UTF-8', 18:22:41 INFO - 'LANGUAGE': 'en_US:en', 18:22:41 INFO - 'LOGNAME': 'cltbld', 18:22:41 INFO - 'MAIL': '/var/mail/cltbld', 18:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:41 INFO - 'MOZ_NO_REMOTE': '1', 18:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:41 INFO - 'NO_EM_RESTART': '1', 18:22:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:41 INFO - 'PWD': '/builds/slave/test', 18:22:41 INFO - 'SHELL': '/bin/bash', 18:22:41 INFO - 'SHLVL': '1', 18:22:41 INFO - 'TERM': 'linux', 18:22:41 INFO - 'TMOUT': '86400', 18:22:41 INFO - 'USER': 'cltbld', 18:22:41 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:41 INFO - '_': '/tools/buildbot/bin/python'} 18:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:41 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 18:22:41 INFO - Cleaning up... 18:22:41 INFO - Return code: 0 18:22:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:22:41 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')]} 18:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:41 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')]} 18:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:41 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 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:41 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 18:22:41 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 18:22:41 INFO - Using env: {'DISPLAY': ':0', 18:22:41 INFO - 'HOME': '/home/cltbld', 18:22:41 INFO - 'LANG': 'en_US.UTF-8', 18:22:41 INFO - 'LANGUAGE': 'en_US:en', 18:22:41 INFO - 'LOGNAME': 'cltbld', 18:22:41 INFO - 'MAIL': '/var/mail/cltbld', 18:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:41 INFO - 'MOZ_NO_REMOTE': '1', 18:22:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:41 INFO - 'NO_EM_RESTART': '1', 18:22:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:41 INFO - 'PWD': '/builds/slave/test', 18:22:41 INFO - 'SHELL': '/bin/bash', 18:22:41 INFO - 'SHLVL': '1', 18:22:41 INFO - 'TERM': 'linux', 18:22:41 INFO - 'TMOUT': '86400', 18:22:41 INFO - 'USER': 'cltbld', 18:22:41 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:41 INFO - '_': '/tools/buildbot/bin/python'} 18:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:41 INFO - Downloading/unpacking psutil>=3.1.1 18:22:41 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 18:22:41 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 18:22:41 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 18:22:41 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 18:22:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:42 INFO - Installing collected packages: psutil 18:22:42 INFO - Running setup.py install for psutil 18:22:42 INFO - building 'psutil._psutil_linux' extension 18:22:42 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 18:22:42 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 18:22:42 INFO - building 'psutil._psutil_posix' extension 18:22:42 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 18:22:43 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 18:22:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:43 INFO - Successfully installed psutil 18:22:43 INFO - Cleaning up... 18:22:43 INFO - Return code: 0 18:22:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:22:43 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')]} 18:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:43 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')]} 18:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:43 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 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:43 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 18:22:43 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 18:22:43 INFO - Using env: {'DISPLAY': ':0', 18:22:43 INFO - 'HOME': '/home/cltbld', 18:22:43 INFO - 'LANG': 'en_US.UTF-8', 18:22:43 INFO - 'LANGUAGE': 'en_US:en', 18:22:43 INFO - 'LOGNAME': 'cltbld', 18:22:43 INFO - 'MAIL': '/var/mail/cltbld', 18:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:43 INFO - 'MOZ_NO_REMOTE': '1', 18:22:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:43 INFO - 'NO_EM_RESTART': '1', 18:22:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:43 INFO - 'PWD': '/builds/slave/test', 18:22:43 INFO - 'SHELL': '/bin/bash', 18:22:43 INFO - 'SHLVL': '1', 18:22:43 INFO - 'TERM': 'linux', 18:22:43 INFO - 'TMOUT': '86400', 18:22:43 INFO - 'USER': 'cltbld', 18:22:43 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:43 INFO - '_': '/tools/buildbot/bin/python'} 18:22:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:22:43 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 18:22:43 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 18:22:43 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 18:22:43 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 18:22:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:22:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:22:44 INFO - Installing collected packages: mozsystemmonitor 18:22:44 INFO - Running setup.py install for mozsystemmonitor 18:22:44 INFO - Successfully installed mozsystemmonitor 18:22:44 INFO - Cleaning up... 18:22:44 INFO - Return code: 0 18:22:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:22:44 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')]} 18:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:44 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')]} 18:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:44 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 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:44 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 18:22:44 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 18:22:44 INFO - Using env: {'DISPLAY': ':0', 18:22:44 INFO - 'HOME': '/home/cltbld', 18:22:44 INFO - 'LANG': 'en_US.UTF-8', 18:22:44 INFO - 'LANGUAGE': 'en_US:en', 18:22:44 INFO - 'LOGNAME': 'cltbld', 18:22:44 INFO - 'MAIL': '/var/mail/cltbld', 18:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:44 INFO - 'MOZ_NO_REMOTE': '1', 18:22:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:44 INFO - 'NO_EM_RESTART': '1', 18:22:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:44 INFO - 'PWD': '/builds/slave/test', 18:22:44 INFO - 'SHELL': '/bin/bash', 18:22:44 INFO - 'SHLVL': '1', 18:22:44 INFO - 'TERM': 'linux', 18:22:44 INFO - 'TMOUT': '86400', 18:22:44 INFO - 'USER': 'cltbld', 18:22:44 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:44 INFO - '_': '/tools/buildbot/bin/python'} 18:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:44 INFO - Downloading/unpacking jsonschema==2.5.1 18:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:22:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:46 INFO - Downloading functools32-3.2.3-2.tar.gz 18:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:22:46 INFO - warning: no files found matching '*.txt' 18:22:46 INFO - no previously-included directories found matching 'build' 18:22:46 INFO - no previously-included directories found matching 'dist' 18:22:46 INFO - no previously-included directories found matching '.git*' 18:22:46 INFO - Installing collected packages: jsonschema, functools32 18:22:46 INFO - Running setup.py install for functools32 18:22:46 INFO - warning: no files found matching '*.txt' 18:22:46 INFO - no previously-included directories found matching 'build' 18:22:46 INFO - no previously-included directories found matching 'dist' 18:22:46 INFO - no previously-included directories found matching '.git*' 18:22:46 INFO - Successfully installed jsonschema functools32 18:22:46 INFO - Cleaning up... 18:22:46 INFO - Return code: 0 18:22:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:46 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 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:46 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 18:22:46 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 18:22:46 INFO - Using env: {'DISPLAY': ':0', 18:22:46 INFO - 'HOME': '/home/cltbld', 18:22:46 INFO - 'LANG': 'en_US.UTF-8', 18:22:46 INFO - 'LANGUAGE': 'en_US:en', 18:22:46 INFO - 'LOGNAME': 'cltbld', 18:22:46 INFO - 'MAIL': '/var/mail/cltbld', 18:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:46 INFO - 'MOZ_NO_REMOTE': '1', 18:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:46 INFO - 'NO_EM_RESTART': '1', 18:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:46 INFO - 'PWD': '/builds/slave/test', 18:22:46 INFO - 'SHELL': '/bin/bash', 18:22:46 INFO - 'SHLVL': '1', 18:22:46 INFO - 'TERM': 'linux', 18:22:46 INFO - 'TMOUT': '86400', 18:22:46 INFO - 'USER': 'cltbld', 18:22:46 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:46 INFO - '_': '/tools/buildbot/bin/python'} 18:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:22:46 INFO - Cleaning up... 18:22:46 INFO - Return code: 0 18:22:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:46 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 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:46 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 18:22:46 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 18:22:46 INFO - Using env: {'DISPLAY': ':0', 18:22:46 INFO - 'HOME': '/home/cltbld', 18:22:46 INFO - 'LANG': 'en_US.UTF-8', 18:22:46 INFO - 'LANGUAGE': 'en_US:en', 18:22:46 INFO - 'LOGNAME': 'cltbld', 18:22:46 INFO - 'MAIL': '/var/mail/cltbld', 18:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:46 INFO - 'MOZ_NO_REMOTE': '1', 18:22:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:46 INFO - 'NO_EM_RESTART': '1', 18:22:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:46 INFO - 'PWD': '/builds/slave/test', 18:22:46 INFO - 'SHELL': '/bin/bash', 18:22:46 INFO - 'SHLVL': '1', 18:22:46 INFO - 'TERM': 'linux', 18:22:46 INFO - 'TMOUT': '86400', 18:22:46 INFO - 'USER': 'cltbld', 18:22:46 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:46 INFO - '_': '/tools/buildbot/bin/python'} 18:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:46 INFO - Downloading/unpacking blobuploader==1.2.4 18:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:47 INFO - Downloading blobuploader-1.2.4.tar.gz 18:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:22:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:22:47 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 18:22:47 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 18:22:47 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 18:22:47 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 18:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:22:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:22:48 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 18:22:48 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 18:22:48 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 18:22:48 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 18:22:48 INFO - Downloading docopt-0.6.1.tar.gz 18:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:22:48 INFO - Installing collected packages: blobuploader, requests, docopt 18:22:48 INFO - Running setup.py install for blobuploader 18:22:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:22:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:22:48 INFO - Running setup.py install for requests 18:22:48 INFO - Running setup.py install for docopt 18:22:49 INFO - Successfully installed blobuploader requests docopt 18:22:49 INFO - Cleaning up... 18:22:49 INFO - Return code: 0 18:22:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:49 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 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:49 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 18:22:49 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 18:22:49 INFO - Using env: {'DISPLAY': ':0', 18:22:49 INFO - 'HOME': '/home/cltbld', 18:22:49 INFO - 'LANG': 'en_US.UTF-8', 18:22:49 INFO - 'LANGUAGE': 'en_US:en', 18:22:49 INFO - 'LOGNAME': 'cltbld', 18:22:49 INFO - 'MAIL': '/var/mail/cltbld', 18:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:49 INFO - 'MOZ_NO_REMOTE': '1', 18:22:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:49 INFO - 'NO_EM_RESTART': '1', 18:22:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:49 INFO - 'PWD': '/builds/slave/test', 18:22:49 INFO - 'SHELL': '/bin/bash', 18:22:49 INFO - 'SHLVL': '1', 18:22:49 INFO - 'TERM': 'linux', 18:22:49 INFO - 'TMOUT': '86400', 18:22:49 INFO - 'USER': 'cltbld', 18:22:49 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:49 INFO - '_': '/tools/buildbot/bin/python'} 18:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:49 INFO - Running setup.py (path:/tmp/pip-fhEMbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:49 INFO - Running setup.py (path:/tmp/pip-j_A9vO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:49 INFO - Running setup.py (path:/tmp/pip-M9l8qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:49 INFO - Running setup.py (path:/tmp/pip-pfIaWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:49 INFO - Running setup.py (path:/tmp/pip-NXGf1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:49 INFO - Running setup.py (path:/tmp/pip-FISTxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:49 INFO - Running setup.py (path:/tmp/pip-MOp38A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:49 INFO - Running setup.py (path:/tmp/pip-Nt9NoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:49 INFO - Running setup.py (path:/tmp/pip-faKukv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:49 INFO - Running setup.py (path:/tmp/pip-dw60Uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:50 INFO - Running setup.py (path:/tmp/pip-sBHaUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:50 INFO - Running setup.py (path:/tmp/pip-IGgVkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:50 INFO - Running setup.py (path:/tmp/pip-yY4MY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:50 INFO - Running setup.py (path:/tmp/pip-V8EUKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:50 INFO - Running setup.py (path:/tmp/pip-WOLm4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:50 INFO - Running setup.py (path:/tmp/pip-Metwga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:50 INFO - Running setup.py (path:/tmp/pip-hG1cSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:22:50 INFO - Running setup.py install for manifestparser 18:22:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Running setup.py install for mozcrash 18:22:50 INFO - Running setup.py install for mozdebug 18:22:50 INFO - Running setup.py install for mozdevice 18:22:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:22:50 INFO - Running setup.py install for mozfile 18:22:51 INFO - Running setup.py install for mozhttpd 18:22:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for mozinfo 18:22:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for mozInstall 18:22:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for mozleak 18:22:51 INFO - Running setup.py install for mozlog 18:22:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for moznetwork 18:22:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for mozprocess 18:22:51 INFO - Running setup.py install for mozprofile 18:22:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for mozrunner 18:22:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:22:51 INFO - Running setup.py install for mozscreenshot 18:22:52 INFO - Running setup.py install for moztest 18:22:52 INFO - Running setup.py install for mozversion 18:22:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:22:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:22:52 INFO - Cleaning up... 18:22:52 INFO - Return code: 0 18:22:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:52 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 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:52 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 18:22:52 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 18:22:52 INFO - Using env: {'DISPLAY': ':0', 18:22:52 INFO - 'HOME': '/home/cltbld', 18:22:52 INFO - 'LANG': 'en_US.UTF-8', 18:22:52 INFO - 'LANGUAGE': 'en_US:en', 18:22:52 INFO - 'LOGNAME': 'cltbld', 18:22:52 INFO - 'MAIL': '/var/mail/cltbld', 18:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:52 INFO - 'MOZ_NO_REMOTE': '1', 18:22:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:52 INFO - 'NO_EM_RESTART': '1', 18:22:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:52 INFO - 'PWD': '/builds/slave/test', 18:22:52 INFO - 'SHELL': '/bin/bash', 18:22:52 INFO - 'SHLVL': '1', 18:22:52 INFO - 'TERM': 'linux', 18:22:52 INFO - 'TMOUT': '86400', 18:22:52 INFO - 'USER': 'cltbld', 18:22:52 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:52 INFO - '_': '/tools/buildbot/bin/python'} 18:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:52 INFO - Running setup.py (path:/tmp/pip-t3EPBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:52 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)) 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:52 INFO - Running setup.py (path:/tmp/pip-EpQuwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:52 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)) 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:52 INFO - Running setup.py (path:/tmp/pip-Rp37R6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:52 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)) 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:52 INFO - Running setup.py (path:/tmp/pip-tKmQxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:52 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)) 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:52 INFO - Running setup.py (path:/tmp/pip-3C8o5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:52 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)) 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:52 INFO - Running setup.py (path:/tmp/pip-fQC9DB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:52 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)) 18:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:52 INFO - Running setup.py (path:/tmp/pip-lE4349-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:53 INFO - Running setup.py (path:/tmp/pip-qvJmgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:53 INFO - Running setup.py (path:/tmp/pip-HCSleX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:53 INFO - Running setup.py (path:/tmp/pip-t56StD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:53 INFO - Running setup.py (path:/tmp/pip-H8l8l3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:53 INFO - Running setup.py (path:/tmp/pip-fleLFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:53 INFO - Running setup.py (path:/tmp/pip-XX3jAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:53 INFO - Running setup.py (path:/tmp/pip-rPl3kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:53 INFO - Running setup.py (path:/tmp/pip-kRvLB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:53 INFO - Running setup.py (path:/tmp/pip-iR4rKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:53 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)) 18:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:53 INFO - Running setup.py (path:/tmp/pip-71ZRX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:53 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)) 18:22:53 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)) 18:22:53 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)) 18:22:53 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)) 18:22:53 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)) 18:22:53 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)) 18:22:53 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)) 18:22:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:54 INFO - Downloading blessings-1.6.tar.gz 18:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:22:54 INFO - Installing collected packages: blessings 18:22:54 INFO - Running setup.py install for blessings 18:22:55 INFO - Successfully installed blessings 18:22:55 INFO - Cleaning up... 18:22:55 INFO - Return code: 0 18:22:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:22:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:55 INFO - Reading from file tmpfile_stdout 18:22:55 INFO - Current package versions: 18:22:55 INFO - argparse == 1.2.1 18:22:55 INFO - blessings == 1.6 18:22:55 INFO - blobuploader == 1.2.4 18:22:55 INFO - docopt == 0.6.1 18:22:55 INFO - functools32 == 3.2.3-2 18:22:55 INFO - jsonschema == 2.5.1 18:22:55 INFO - manifestparser == 1.1 18:22:55 INFO - mozInstall == 1.13 18:22:55 INFO - mozcrash == 1.0 18:22:55 INFO - mozdebug == 0.1 18:22:55 INFO - mozdevice == 0.51 18:22:55 INFO - mozfile == 1.2 18:22:55 INFO - mozhttpd == 0.7 18:22:55 INFO - mozinfo == 0.10 18:22:55 INFO - mozleak == 0.1 18:22:55 INFO - mozlog == 3.5 18:22:55 INFO - moznetwork == 0.27 18:22:55 INFO - mozprocess == 0.25 18:22:55 INFO - mozprofile == 0.28 18:22:55 INFO - mozrunner == 6.13 18:22:55 INFO - mozscreenshot == 0.1 18:22:55 INFO - mozsystemmonitor == 0.3 18:22:55 INFO - moztest == 0.8 18:22:55 INFO - mozversion == 1.4 18:22:55 INFO - psutil == 3.1.1 18:22:55 INFO - requests == 1.2.3 18:22:55 INFO - wsgiref == 0.1.2 18:22:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:55 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 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:55 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 18:22:55 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 18:22:55 INFO - Using env: {'DISPLAY': ':0', 18:22:55 INFO - 'HOME': '/home/cltbld', 18:22:55 INFO - 'LANG': 'en_US.UTF-8', 18:22:55 INFO - 'LANGUAGE': 'en_US:en', 18:22:55 INFO - 'LOGNAME': 'cltbld', 18:22:55 INFO - 'MAIL': '/var/mail/cltbld', 18:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:55 INFO - 'MOZ_NO_REMOTE': '1', 18:22:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:55 INFO - 'NO_EM_RESTART': '1', 18:22:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:55 INFO - 'PWD': '/builds/slave/test', 18:22:55 INFO - 'SHELL': '/bin/bash', 18:22:55 INFO - 'SHLVL': '1', 18:22:55 INFO - 'TERM': 'linux', 18:22:55 INFO - 'TMOUT': '86400', 18:22:55 INFO - 'USER': 'cltbld', 18:22:55 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:55 INFO - '_': '/tools/buildbot/bin/python'} 18:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:55 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)) 18:22:55 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)) 18:22:55 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)) 18:22:55 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)) 18:22:55 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)) 18:22:55 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)) 18:22:55 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)) 18:22:55 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)) 18:22:55 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)) 18:22:55 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:22:56 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)) 18:22:56 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)) 18:22:56 INFO - Installing collected packages: simplejson 18:22:56 INFO - Running setup.py install for simplejson 18:22:56 INFO - building 'simplejson._speedups' extension 18:22:56 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 18:22:57 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 18:22:57 INFO - Successfully installed simplejson 18:22:57 INFO - Cleaning up... 18:22:57 INFO - Return code: 0 18:22:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22: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')]} 18:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1246d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', '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 18:22:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:22:57 INFO - Using env: {'DISPLAY': ':0', 18:22:57 INFO - 'HOME': '/home/cltbld', 18:22:57 INFO - 'LANG': 'en_US.UTF-8', 18:22:57 INFO - 'LANGUAGE': 'en_US:en', 18:22:57 INFO - 'LOGNAME': 'cltbld', 18:22:57 INFO - 'MAIL': '/var/mail/cltbld', 18:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:57 INFO - 'MOZ_NO_REMOTE': '1', 18:22:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:22:57 INFO - 'NO_EM_RESTART': '1', 18:22:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:57 INFO - 'PWD': '/builds/slave/test', 18:22:57 INFO - 'SHELL': '/bin/bash', 18:22:57 INFO - 'SHLVL': '1', 18:22:57 INFO - 'TERM': 'linux', 18:22:57 INFO - 'TMOUT': '86400', 18:22:57 INFO - 'USER': 'cltbld', 18:22:57 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:57 INFO - '_': '/tools/buildbot/bin/python'} 18:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:22:57 INFO - Cleaning up... 18:22:57 INFO - Return code: 0 18:22:57 INFO - Running post-action listener: _resource_record_post_action 18:22:57 INFO - Running post-action listener: _start_resource_monitoring 18:22:57 INFO - Starting resource monitoring. 18:22:57 INFO - [mozharness: 2017-08-03 01:22:57.968025Z] Finished create-virtualenv step (success) 18:22:57 INFO - [mozharness: 2017-08-03 01:22:57.968541Z] Running install step. 18:22:57 INFO - Running pre-action listener: _resource_record_pre_action 18:22:57 INFO - Running main action method: install 18:22:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:58 INFO - Reading from file tmpfile_stdout 18:22:58 INFO - Detecting whether we're running mozinstall >=1.0... 18:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:22:58 INFO - Reading from file tmpfile_stdout 18:22:58 INFO - Output received: 18:22:58 INFO - Usage: mozinstall [options] installer 18:22:58 INFO - Options: 18:22:58 INFO - -h, --help show this help message and exit 18:22:58 INFO - -d DEST, --destination=DEST 18:22:58 INFO - Directory to install application into. [default: 18:22:58 INFO - "/builds/slave/test"] 18:22:58 INFO - --app=APP Application being installed. [default: firefox] 18:22:58 INFO - mkdir: /builds/slave/test/build/application 18:22:58 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'] 18:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:23:16 INFO - Reading from file tmpfile_stdout 18:23:16 INFO - Output received: 18:23:16 INFO - /builds/slave/test/build/application/firefox/firefox 18:23:16 INFO - Running post-action listener: _resource_record_post_action 18:23:16 INFO - [mozharness: 2017-08-03 01:23:16.362913Z] Finished install step (success) 18:23:16 INFO - [mozharness: 2017-08-03 01:23:16.363200Z] Running setup-mitmproxy step. 18:23:16 INFO - Running pre-action listener: _resource_record_pre_action 18:23:16 INFO - Running main action method: setup_mitmproxy 18:23:16 INFO - Skipping: mitmproxy is not required 18:23:16 INFO - Running post-action listener: _resource_record_post_action 18:23:16 INFO - [mozharness: 2017-08-03 01:23:16.364331Z] Finished setup-mitmproxy step (success) 18:23:16 INFO - [mozharness: 2017-08-03 01:23:16.364551Z] Running run-tests step. 18:23:16 INFO - Running pre-action listener: _resource_record_pre_action 18:23:16 INFO - Running pre-action listener: _set_gcov_prefix 18:23:16 INFO - Running main action method: run_tests 18:23:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:23:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:23:16 INFO - Python 2.7.3 18:23:16 INFO - Return code: 0 18:23:16 INFO - grabbing minidump binary from tooltool 18:23: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')]} 18:23:16 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 0x1243eb0>, '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 0x1244db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1245240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:23:16 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 18:23:16 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 18:23:16 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 18:23:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:23:16 INFO - Return code: 0 18:23:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:23:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:23:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:23:16 INFO - ENV: RUST_BACKTRACE is now 1 18:23:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:23:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:23:16 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/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 18:23:16 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/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 18:23:16 INFO - Using env: {'DISPLAY': ':0', 18:23:16 INFO - 'HOME': '/home/cltbld', 18:23:16 INFO - 'LANG': 'en_US.UTF-8', 18:23:16 INFO - 'LANGUAGE': 'en_US:en', 18:23:16 INFO - 'LOGNAME': 'cltbld', 18:23:16 INFO - 'MAIL': '/var/mail/cltbld', 18:23:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:23:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:16 INFO - 'MOZ_NO_REMOTE': '1', 18:23:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:23:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:23:16 INFO - 'NO_EM_RESTART': '1', 18:23:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:16 INFO - 'PWD': '/builds/slave/test', 18:23:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:23:16 INFO - 'RUST_BACKTRACE': '1', 18:23:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:23:16 INFO - 'SHELL': '/bin/bash', 18:23:16 INFO - 'SHLVL': '1', 18:23:16 INFO - 'STYLO_FORCE_ENABLED': '1', 18:23:16 INFO - 'TERM': 'linux', 18:23:16 INFO - 'TMOUT': '86400', 18:23:16 INFO - 'USER': 'cltbld', 18:23:16 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240', 18:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:16 INFO - '_': '/tools/buildbot/bin/python'} 18:23:16 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/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 18:23:16 INFO - mozversion application_buildid: 20170803001202 18:23:16 INFO - mozversion application_changeset: 6f1914a4f241b8ac62953de069296397b7645cd1 18:23:16 INFO - mozversion application_display_name: Nightly 18:23:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:23:16 INFO - mozversion application_name: Firefox 18:23:16 INFO - mozversion application_remotingname: firefox 18:23:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:23:16 INFO - mozversion application_vendor: Mozilla 18:23:16 INFO - mozversion application_version: 57.0a1 18:23:16 INFO - mozversion platform_buildid: 20170803001202 18:23:16 INFO - mozversion platform_changeset: 6f1914a4f241b8ac62953de069296397b7645cd1 18:23:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:23:16 INFO - mozversion platform_version: 57.0a1 18:23:16 INFO - using testdate: 1501723396 18:23:16 INFO - actual date: 1501723396 18:23:16 INFO - starting webserver on 'localhost:56989' 18:23:16 INFO - SUITE-START | Running 2 tests 18:23:16 INFO - TEST-START | dromaeo_css 18:23:16 INFO - Initialising browser for dromaeo_css test... 18:23:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56989/getInfo.html -profile /tmp/tmp2ja09r/profile 18:23:16 INFO - TEST-INFO | started process 12191 (/builds/slave/test/build/application/firefox/firefox http://localhost:56989/getInfo.html) 18:23:22 INFO - TEST-INFO | 12191: exit 0 18:23:22 INFO - Browser initialized. 18:23:22 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:22 INFO - TEST-INFO | started process 12416 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2ja09r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:23 INFO - PID 12416 | 18:23:23 INFO - PID 12416 | (/builds/slave/test/build/application/firefox/firefox:12470): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:23:23 INFO - PID 12416 | 18:23:34 INFO - PID 12416 | RSS: Main: 174354432 18:23:34 INFO - PID 12416 | 18:26:34 INFO - PID 12416 | Cycle 1(1): loaded http://localhost:56989/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56989/tests/dromaeo/cssquery-ext.html) 18:26:35 INFO - PID 12416 | RSS: Main: 176648192 18:26:35 INFO - PID 12416 | 18:29:37 INFO - PID 12416 | Cycle 1(1): loaded http://localhost:56989/tests/dromaeo/cssquery-ext.html (next: http://localhost:56989/tests/dromaeo/cssquery-jquery.html) 18:29:37 INFO - PID 12416 | RSS: Main: 174624768 18:29:37 INFO - PID 12416 | 18:32:39 INFO - PID 12416 | Cycle 1(1): loaded http://localhost:56989/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56989/tests/dromaeo/cssquery-mootools.html) 18:32:40 INFO - PID 12416 | RSS: Main: 174776320 18:32:40 INFO - PID 12416 | 18:35:42 INFO - PID 12416 | Cycle 1(1): loaded http://localhost:56989/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56989/tests/dromaeo/cssquery-prototype.html) 18:35:42 INFO - PID 12416 | RSS: Main: 172732416 18:35:42 INFO - PID 12416 | 18:38:46 INFO - PID 12416 | Cycle 1(1): loaded http://localhost:56989/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56989/tests/dromaeo/cssquery-yui.html) 18:38:47 INFO - PID 12416 | RSS: Main: 178696192 18:38:47 INFO - PID 12416 | 18:41:49 INFO - PID 12416 | Cycle 1(1): loaded http://localhost:56989/tests/dromaeo/cssquery-yui.html (next: http://localhost:56989/tests/dromaeo/cssquery-dojo.html) 18:41:49 INFO - PID 12416 | RSS: Main: 178778112 18:41:49 INFO - PID 12416 | 18:41:49 INFO - PID 12416 | __start_tp_report 18:41:49 INFO - PID 12416 | _x_x_mozilla_page_load 18:41:49 INFO - PID 12416 | _x_x_mozilla_page_load_details 18:41:49 INFO - PID 12416 | |i|pagename|runs| 18:41:49 INFO - PID 12416 | |0;dojo.html;3144.4025635129324;3147.9370412591747;3148.79532830366;3181.061586831884;3199.4880819068953;6172.074188871671;6199.690015499224;6223.875522489548;6244.3755624437645;6266.373362663731;6138.447539721425;6180.41286077823;6217.222847144107;6243.250809902806;6258.123862659231;5649.096144616862;5668.943310566903;5690.857728556778;5698.259226300575;5707.315122185343;5693.316801983747;5696.430356964291;5716.456936591017;5720.79977200798;5724.2558467399385;5665.773369065232;5682.147677848315;5687.914681279784;5717.685527295997;5798.594098413092;5771.3074431068335;5779.422057794229;5779.45095215958;5796.50729687977;5836.6789826559525;5636.210930469738;5668.829935101912;5717.113847353667;5733.283339582552;5736.368999410061;5928.49607783339;6010.188624535675;6029.8191054268445;6033.788817391414;6135.417135372132;6647.966760166217;6715.899261511081;6738.393544580962;6751.189857217117;6818.352256535622;6747.359000894907;6783.084283621746;6813.7955861324235;6836.316368363174;6871.896921546131;6206.472449841724;6254.21822272216;6259.155014073087;6273.027680709497;6345.555811093225;6860.0738900248825;6898.206706228848;6922.723091076401;6923.6884340204815;6930.480213983971;6602.73589056442;6608.933910660928;6627.867442651216;6646.734130634721;6648.66756662165;6614.867702646016;6647.4682025438815;6657.3342665733035;6674.199096108498;6741.494387920926;6628.5360024798765;6657.201135863727;6710.832229194211;6716.764913228014;6745.426638735665;6621.205455345389;6648.833779155463;6684.86630267392;6687.732490700415;6784.796456106226;5262.684238945675;5266.8946621068135;5295.867603309948;5384.326959130109;5400.081986062379;4599.103174880933;4653.255479123324;4660.231061874752;4671.76641167947;4744.644151688568;5819.010768169422;5837.766489340379;5920.348761636216;5924.14099955504;5933.050711886078;6590;6691.631960242136;6707.731690732316;6715.328467153246;6748.156480439945;5736.139579063174;5824.242848429677;5831.300243970751;5833.125031245262;5836.124581312837;5764.769409223584;5768.942310576925;5858.912116318259;5859.94140058594;5869.060950248026;6429.678516074177;6492.64290464029;6502.479801615859;6506.056621789909;6509.218893732783;6658.400743933069;6685.76599819004;6763.458923286027;6777.999999999902;6781.694823732845;6325.778597749057;6426.807195784134;6433.517486188648;6438.581492202992;6452.12896258999;6555.4427873631275;6566.6388348641285;6569.4744420445295;6577;6604.9009264862025;6673.799786006428;6701.731930722716;6703.966480167568;6735.090762746966;6780.69486873082;6420.711068002044;6430.324815894451;6487.351264873475;6494.740210391531;6506.219253689587;6425.967870160806;6496.447801936699;6506.056621789909;6518.120053792677;6539.542232043711;6335.556511044183;6373.171487706569;6438.066480360228;6440.806775796546;6453.128827608213;6528.118703974903;6544.280129185881;6563;6597.868042639025;6652.201735791736;6204.348543402878;6260.186175797117;6283.937160628335;6420.903686444801;6436.324185960407 18:41:49 INFO - PID 12416 | |1;ext.html;825.2490233887164;839.6221700234896;867.648602316062;888.8933328000645;891.3448615267777;40087.39868901969;40759.796201019024;40787.18425631485;41013.7949310253;41204.79397603015;37064.81467592665;37328.440073398924;37486.625133748654;37638.811805941;37731.24537509255;6661.90007149892;6793.252742198354;6823.69293381799;6828.248892621832;6839.1451068616425;6668.399844014038;6777.593344399327;6779.661016949158;6780.423663988567;6785.016172654962;6749.831254218635;7299.927000730005;7579.81050473737;7593.582352970584;7598.202188770182;7486.812829679275;7498.850022999564;7527.510711803742;7528.811779705469;7577.659005344718;7226.855462890713;7366.079240094989;7371.88942165868;7382.630868456556;7509.4743367964265;39213.803930980444;39252.21495570073;39268.60731392678;39303;39425.21149576992;3552.413851714464;3601.2617413430185;3615.493830863681;3620.31214069326;3627.256412435471;31847;31855.203619909455;31938.361232775445;31945;31987.52018719721;4703.341532185496;4811.254255590389;4895.339129217546;4906.141425250603;4912.631552633531;188904;191404;191979;193432;196783.0160849201;50152.749236253585;50454.747726261136;50570.98858022893;50673.74663126661;50759.746201268754;51884.74057629688;51994.48005519972;52001.219981700306;52007;52146.47853521492;16014.679706405806;16132.67734645324;16193.271302791403;16195.757063644054;16293.674126517404;13094.869051309555;13102.082854200115;13102.279374634296;13162.473501060042;13218.867811321956;14697.118172909446;14702.55892323232;14757.852421475647;14773.33519991603;15059.096454212782;5689.175069614883;5718.771249150071;5830.017793950077;5850.896508335985;5874.766299077156;978.7406337320616;981.2052237687496;1013.7110923386799;1015.8628585141059;1017.6949210039267;23062.8846855768;23261.418464538183;23400.414989625395;23478.413039674146;23691.526169476514;1113.287496002559;1114.3815182573587;1116.3636727065496;1118.8321751737142;1120.6974116988367;12515.499380024698;12577.748445031231;12579.874201258053;12586.874131258754;12609.810852837214;214803;215087;215276;215955;216482;53711;53948.73025634847;54115.72942135264;54247;54248;1419.3683810704138;1468.919209443491;1487.732208202534;1493.1936754152637;1494.8505149485181;22940.541189176456;22960;23052.193173238855;23402.882985585646;23455.88272058697;38905.221895561364;39011.219775603764;39188.41217381799;39214.607853921094;39229.215415692095;192273;193161;193194;193432;194117;30122.548161778028;30714.692853071185;30887;31272.06183814489;34031;42631.147377052905;44170;44783.328250075625;45018.77490612657;45369.54630453785;34627.65372346345;35129.82435087808;35187.82406087953;35659.643403565635;35676;34475;34509.827450862584;34566.48150277697;34681.13297167591;34887.12782180466;37108.257834843695;37901.43147852839;37939.24121517499;38057.23885522219;38226.617733822306;33715;33748.662513374555;33991.66008339984;34443.4833477493;34453.65546344605;23841.880790595937;25491.23526294214;25535.723213839607;25725.228243152735;25808.741912580634 18:41:49 INFO - PID 12416 | |2;jquery.html;548.9698066606335;562.0483540012493;562.4544192133632;575.3239943066897;597.271328978646;21785;22427.32718018458;22537.323880283577;22596.66105008424;23242.186523471682;25395.36511587211;27260.72739272607;27415.588766168523;27444.588331175048;27534.173974780737;6483.286838447767;6606.570572912769;6624.635645039521;6635.966820165892;6699.229588597301;6200.410960958701;6444.484441244689;6537.967310163443;6545.443637290836;6553.934460655404;6537.7384904604;6541.901871471931;6543.607383556978;6545.934540654604;6546.738130474775;6426.742930282807;6499.155109835691;6499.155109835739;6511.511636627272;6534.183227096613;6008.158857759917;6040.697965101727;6055.364186760415;6067.787627433063;6075.939240607581;27773.166804995883;30655.386892262253;31135;31198.532022019688;31524.684753152633;1915.9425217243504;1975.2099160335838;1978.574606459618;1982.4944639117077;1990.4725247809342;19339;19573.804261957484;19691;19723.21107155712;19770.110345034507;2285.2915596165244;2338.3101984914492;2375.9524809503887;2386.8925898334614;2398.8680622565753;140721;142986;145758;150966;151432;14285.85714142866;15073.849261507245;15166;15256.618584535254;15289.923550382176;23740.169094081968;23839.7616023841;23875.16436924699;23896.88051559731;23934.162304319267;10159.644412445678;10212.183025357954;10220.795584088277;10354.223433242538;10389;9451.149396554343;9540.427574345562;9579.616815327308;9624;9643.517824108767;7624.19945905683;8826.91173088261;9206.447613143233;9379.296552758571;9604.66383676568;2536.4039450729065;2626.338249606925;2679.196241127654;2759;2770.7229277072133;951.5432592355708;960.4285450157146;965.2480747443368;978.4031740638205;987.4865070163474;20381.082851271727;20492.590148196952;20864;21029.89485052565;21227.575448490945;1019.3170575714289;1040.0795296162798;1040.8959104089531;1052.2686732720686;1053.4153544782719;11164.330140191614;11475.368854712904;11493.655190344303;11522.193446458667;11616.128790340757;461119;466219;475408.69114074175;478074;484277;113042;113600.43199783948;114942;115186.42406787912;115237.84762152439;1152.124385467051;1199.1785626845638;1224.9755004899853;1236.0544183699476;1236.1964722930168;17799.19903604367;18245.817541824414;18342.81657183411;21714.24000159987;23069.077236910336;55999.44000560105;59969;61209;61719.382806171365;64225.036624451604;68434.3156568428;69010;69028.65485672539;69585.30414695991;69977.65011175115;12668.809967850306;13164.14433061848;14732;14776.891733119845;15316.770248446504;23559.175428859908;23655;24014.87992560026;24031.158909438087;24120.517674560688;18307.450776476726;18694.719579206052;18727.625447491246;18798.812011880254;18810.905945470186;17083.743743843603;17234.82765172332;17357.30570777155;17628.647427051645;17817.73273400926;18721.81278187255;19389;19537.60924781468;19600.705989409886;19802.90098549498;15000.924995374953;15022.549323520312;15067.698646026798;15097.47158849435;15127;18264.634707305515;18657.81342186561;19150.904245478683;19167.137478813765;19173.808261917202 18:41:49 INFO - PID 12416 | |3;mootools.html;1335.198880671597;1406.94372225111;1410.1961881727416;1419.2123371528796;1423.6867889064401;13486.190828550294;13764.931175344134;14051.789223161648;14076.64808379791;14094.295285235736;14368.856311436883;14576.854231457683;14597.197154156516;14620.926895365536;14647.121172729618;2620.2401303621928;2716.633254510645;2773.0294396961003;2782.7634651054686;2784.4570308789794;2700.2844246274753;2731.426400455907;2732.508148533273;2740.0409856550245;2748.0656576763895;2667.7465640764194;2677.759001689857;2732.221316924677;2733.931651708713;2734;2563.974360256411;2638.023931145479;2645.1271080543374;2647.0470630573072;2648.1923013480978;2586.599077143027;2590.378309205796;2604.414006848463;2617.7251388604304;2655.216711070239;13486.258255795927;13792.724145517033;13800.447982080805;13846.861531384759;13864.722705545933;7495.287947644965;7724.266194711549;7729.111152217454;7765.456418050739;7786.065672119325;4930.852074437773;5246.658967167122;5281.788728450857;5319.813806516792;5321.308229930123;1750.1424302092012;1751.448293787453;1770.5662112782322;1828.570285982793;1837.3110083718605;22327.66508502374;22530.77469225303;22593.096276148935;22710.432239194153;22711.20510782131;6439.710213040425;7096.574205547684;7150.678219480136;7345.375643070399;7345.632718364061;15711.135887526067;15848.1283529407;15869.20653967297;15877.761833572506;15888.682226355408;7506.28690274423;7618.961905190438;7633.69465221385;7664.65509052094;7675.808104797425;6850.588964662036;7063.081799366049;7069.858602827914;7243.674034668453;7256.2743725627015;4977.676451030671;5180.171172612364;5189.481051894781;5215.452377500384;5277.973610131925;1524.4740564505228;1526.0538466151058;1529.5029115537495;1549.775282584019;1557.5171696774037;822.3708862720024;837.1377481194379;840.1682334488812;857.6955180910811;859.5616235719828;6616.27221005689;6649.867002659919;6689.130413046273;6748.190217173872;7377.704891804267;939.7321763297427;944.4474982135528;946.6024269806697;948.9145976862116;954.1126752120595;4315.3095504719095;4660.044690838437;4713.457952335525;4715.929261061086;4783.545563171493;18749.34377296788;19073.713894291595;19363.51591210231;19585.804141958684;19703.901480492506;18841.623167536847;19015.33446329372;19036.904815475835;19210;19279.132439040277;10509.264351495474;10812.891871081345;11054.281471704475;11069.169812264112;11086.057685096695;7148.213696493278;7281.955976539565;7360.521566098187;7383;7412.740554080581;16361.181940902668;16725.749113763548;17169.141542923055;17243;17249;17052.403165889133;17058.744118837978;17167.399141030004;17316.134193290538;17669.469915902544;11171.106311495223;11611.825822612498;11701;11743.17797754149;11744.58893938708;13080.73838523205;13088.214707117604;13161.47354105825;13238.470461181445;13245.337733113498;10779.191560632828;10817.837732434176;10983.121350291798;11027.779444411228;11045.558177672803;10022.398656080659;10138.188944884245;10166.949165254422;10191.643292484727;10370.066693997576;9419.811603768023;9687.41875487473;9700.466474343979;9702.369345992487;9710.126088652056;6296.307406185408;6324.557280990287;6332.778352757631;6360.809175724736;6419.197600299963;6560.212774466903;6571.408573228432;6628.304028077176;6679.76620818269;6712.060311556288 18:41:49 INFO - PID 12416 | |4;prototype.html;483.16653772242887;500.70458429526593;527.6596595196098;531.1422053383782;531.54552857307;6194.7212375443105;6292.46514046306;6306.558540902127;6327.683615819214;6363.490920726336;5889.234399528055;5964.3617393389995;5988.490978266842;6054.788082417116;6132.1721567588365;4817.494163112875;4838.177509823338;4881.755912204393;4890.828820991266;4902.264660300948;4791.736454495001;4937.975310123445;4941.2094064949615;4953.628477864157;4964.180910149821;4862.086020753292;4893.730844803535;4898.902021959559;4901.436334821487;4911.607071434295;4835.758212089417;4881.170201065827;4881.755912204376;4900.681455705403;4910.8035678573;4344.89137771555;4363.3454981752675;4382.737035777864;4389.912201755979;4405.096955124224;6001.309849367335;6029.276486821565;6057.636541807506;6064.9090263646085;6070.605410648338;5632.155176723524;5649.548036157097;5657.745401456944;5672.9432705672825;5681.289838770153;6493.123428337209;6494.610323380565;6518.924377477758;6553.180852393451;6557.606543607399;4804.567588917014;4806.735629540374;4829.855104346875;4841.830535931225;4858.587020103296;6856.177258728934;6886.763221285987;6893.689783959633;6911.308869113099;6969.884818429077;4963.60291176705;4971.577415919687;4972.5276098770555;4977.278294647256;4986.601071914238;4937.23472861707;4938.802447902043;5012.974935125301;5036.395632524121;5059.822906198266;5664.498907789439;5672.659640421588;5687.744051517691;5690.573207009491;5696.458836410535;6647.0029495576055;6659.53383263167;6664.133662623828;6664.5334826561675;6668.133142691516;1616.7089923813592;1647.5963388969778;1684.0990070312569;1745.930162793474;1750.0812073661175;1192.9880701193051;1210.3706072842074;1217.2392254840724;1219.426869371394;1230.7476967221698;5780.537556995514;5784.958707432703;5792.594518383757;5814.883702326015;5840.29916410025;6568.737250510022;6602.537822352389;6610.7686230982645;6675.46596272297;6745.5952642841585;5741.712914354265;5759.798407055733;5795.217645617872;5805.564582656318;5859.384764599741;3534.3991521441417;3552.2362692020824;3570;3590.551181102362;3604.116991337087;6467.708953097122;6472.870542589122;6515.4787616990525;6526.706298216592;6540.444062254762;6424.967875160688;6846.246912839583;6856.211535673362;6869.931300686929;6895.4138898194215;6401.295857455583;6405.967970160119;6428.100065990765;6452.870942581122;6487.935120648733;6509.4792416605615;6558.475321974326;6613.537052406477;6621.370969758058;6645.800625981228;6489.902651460137;6625.17185351831;6638.90041649366;6654.90017649726;6685.130932978682;6348.809535713936;6349.49204063683;6442.742290308523;6456.094757359113;6483.157189565326;4869.269609558594;4891.241857512091;4899.608031357411;4925.684928083795;4927.187014142626;6544.985527243285;6696.933030669631;6742.460603151638;6767.221769496571;6786.21958474762;6376.776812811529;6436.67816609177;6454.806355809334;6457.580257283262;6519.869602607826;6478.805635830932;6540.967295163494;6552.803415897531;6557.639329836905;6578.605283682994;6303.352591911316;6351.96824015877;6402.439024390281;6405.551611387158;6450.322716114741;6532.150820393318;6563.1796025496815;6572.8356791080605;6576.731027795403;6588.407043365928;6319.747210111544;6343.175387199634;6353.96823015882;6355.396237357444;6369.20384951881 18:41:49 INFO - PID 12416 | |5;yui.html;892.593869789246;917.8898532176138;918.7519369770166;925.3938670171036;927.4157280913024;17640.38258660947;18623.906880465594;19151.712724309113;19174.616507669874;19387;20491.69262461064;20834.68747968778;21074.789252107475;21313.467163320925;21463.67804482934;1028.8611037509952;1047.8690163729534;1051.6582110813977;1054.910332621728;1056.0706578211164;971.2861047130353;1040.604570263299;1041.6093964763213;1048.444324508008;1051.7002654243543;1089.84742136101;1090.487470888681;1091.0943916549286;1093.6227001684406;1093.7046997310722;1090.1823632275793;1091.5360971587083;1092.2900114925283;1095.901368876797;1096.7313008312935;793.4089103617841;798.3652995868299;800.6837299266815;800.8358286551243;800.8518424091561;18953.43139705811;19364.419067428;19416.805831941783;19426.902865485583;19722;20370.694439583418;20465.897670511553;20781.792182078138;20903.26838560658;20912;14828.406863725439;14965.625859353495;15076.040114785117;15106.697866042727;15183.316750746133;3385.289089291252;3461.0309113448275;3463.5670541182353;3463.8441270142903;3465.064432603183;119634;120347;122952.38523807413;130102;130853;16363.263653135638;16602.169891505375;17023.14884255782;17032.744508832377;17048.318067277418;27234.319142021606;30150.69849301523;30820.383592328475;30821.383572328876;30849.84575077155;8686.435381700296;9090.500022498694;9103.544822758968;9166.129217724305;9166.495842728715;4094.0788322627172;4182.895427614334;4185.707000509996;4189.05746207107;4200.4959404871;5173.715445650525;5319.042572336939;5323.653962492503;5335.413104558495;5337.8131765388025;1016.5425558498705;1029.1098200056897;1047.1151876664208;1048.0672201740458;1058.8623478947688;658.5818005566404;676.9695363708645;685.8971154326891;694.833240022391;700.0409439068509;11948.880511194951;12074.517019319304;12238.925235978695;12537.623871283877;12923.289219092852;661.1240106858431;673.7574473189648;680.4896327754184;681.2846511163259;692.8025512728949;5297.841064768063;5376.596755243294;5384.968834904931;5516.7517461714315;5531.944680553143;96756.51621741986;98851;103552.48223758832;107453;108086;89746.10253897509;89980.5500972491;90449;90586.54706726424;91742.54128729313;77301;77592.44815103666;82604.58697706593;82947;83143;12476;12835.892514395304;12896.226226426445;13096.803547946978;13253.933730331286;36047;36729.816350918074;36898.07754806151;37390.62609373871;38949.61050389573;51039.95840166352;64423;67525.32474675191;68201;69261;14413;15275.923620381827;16110.113943733208;18249.361272355403;18623.348182813537;17464.65070698604;19497.512562186057;19683.901580492005;19815;19877.701834472206;18446.815531845048;18818.15318310652;18820.811791881904;18959.90520047391;19072.237110515423;18865.528361791065;18972.525686857938;19129.90435047816;19174.61650766949;19313.130909108844;18621;18636.34772782946;18689.90655046716;18706.532336691147;18734.906325468284;13792.172469651852;13871.722565548835;13965.371558280105;14001.859981400463;14056.437742490594;14191;14212.92893535567;14264.144151350953;14286.642833929234;14467.855321447072 18:41:49 INFO - PID 12416 | __end_tp_report 18:41:49 INFO - PID 12416 | __start_cc_report 18:41:49 INFO - PID 12416 | _x_x_mozilla_cycle_collect,395 18:41:49 INFO - PID 12416 | __end_cc_report 18:41:49 INFO - PID 12416 | __startTimestamp1501724509418__endTimestamp 18:41:49 INFO - PID 12416 | 18:41:49 INFO - PID 12416 | ------- Summary: start ------- 18:41:49 INFO - PID 12416 | Number of tests: 6 18:41:49 INFO - PID 12416 | 18:41:49 INFO - PID 12416 | [#0] dojo.html Cycles:165 Average:6151.82 Median:6420.90 stddev:727.87 (11.3%) stddev-sans-first:690.81 18:41:49 INFO - PID 12416 | Values: 3144.4 3147.9 3148.8 3181.1 3199.5 6172.1 6199.7 6223.9 6244.4 6266.4 6138.4 6180.4 6217.2 6243.3 6258.1 5649.1 5668.9 5690.9 5698.3 5707.3 5693.3 5696.4 5716.5 5720.8 5724.3 5665.8 5682.1 5687.9 5717.7 5798.6 5771.3 5779.4 5779.5 5796.5 5836.7 5636.2 5668.8 5717.1 5733.3 5736.4 5928.5 6010.2 6029.8 6033.8 6135.4 6648.0 6715.9 6738.4 6751.2 6818.4 6747.4 6783.1 6813.8 6836.3 6871.9 6206.5 6254.2 6259.2 6273.0 6345.6 6860.1 6898.2 6922.7 6923.7 6930.5 6602.7 6608.9 6627.9 6646.7 6648.7 6614.9 6647.5 6657.3 6674.2 6741.5 6628.5 6657.2 6710.8 6716.8 6745.4 6621.2 6648.8 6684.9 6687.7 6784.8 5262.7 5266.9 5295.9 5384.3 5400.1 4599.1 4653.3 4660.2 4671.8 4744.6 5819.0 5837.8 5920.3 5924.1 5933.1 6590.0 6691.6 6707.7 6715.3 6748.2 5736.1 5824.2 5831.3 5833.1 5836.1 5764.8 5768.9 5858.9 5859.9 5869.1 6429.7 6492.6 6502.5 6506.1 6509.2 6658.4 6685.8 6763.5 6778.0 6781.7 6325.8 6426.8 6433.5 6438.6 6452.1 6555.4 6566.6 6569.5 6577.0 6604.9 6673.8 6701.7 6704.0 6735.1 6780.7 6420.7 6430.3 6487.4 6494.7 6506.2 6426.0 6496.4 6506.1 6518.1 6539.5 6335.6 6373.2 6438.1 6440.8 6453.1 6528.1 6544.3 6563.0 6597.9 6652.2 6204.3 6260.2 6283.9 6420.9 6436.3 18:41:49 INFO - PID 12416 | 18:41:49 INFO - PID 12416 | [#1] ext.html Cycles:180 Average:37415.91 Median:3617.90 stddev:51959.45 (1436.2%) stddev-sans-first:52032.57 18:41:49 INFO - PID 12416 | Values: 825.2 839.6 867.6 888.9 891.3 40087.4 40759.8 40787.2 41013.8 41204.8 37064.8 37328.4 37486.6 37638.8 37731.2 6661.9 6793.3 6823.7 6828.2 6839.1 6668.4 6777.6 6779.7 6780.4 6785.0 6749.8 7299.9 7579.8 7593.6 7598.2 7486.8 7498.9 7527.5 7528.8 7577.7 7226.9 7366.1 7371.9 7382.6 7509.5 39213.8 39252.2 39268.6 39303.0 39425.2 3552.4 3601.3 3615.5 3620.3 3627.3 31847.0 31855.2 31938.4 31945.0 31987.5 4703.3 4811.3 4895.3 4906.1 4912.6 188904.0 191404.0 191979.0 193432.0 196783.0 50152.7 50454.7 50571.0 50673.7 50759.7 51884.7 51994.5 52001.2 52007.0 52146.5 16014.7 16132.7 16193.3 16195.8 16293.7 13094.9 13102.1 13102.3 13162.5 13218.9 14697.1 14702.6 14757.9 14773.3 15059.1 5689.2 5718.8 5830.0 5850.9 5874.8 978.7 981.2 1013.7 1015.9 1017.7 23062.9 23261.4 23400.4 23478.4 23691.5 1113.3 1114.4 1116.4 1118.8 1120.7 12515.5 12577.7 12579.9 12586.9 12609.8 214803.0 215087.0 215276.0 215955.0 216482.0 53711.0 53948.7 54115.7 54247.0 54248.0 1419.4 1468.9 1487.7 1493.2 1494.9 22940.5 22960.0 23052.2 23402.9 23455.9 38905.2 39011.2 39188.4 39214.6 39229.2 192273.0 193161.0 193194.0 193432.0 194117.0 30122.5 30714.7 30887.0 31272.1 34031.0 42631.1 44170.0 44783.3 45018.8 45369.5 34627.7 35129.8 35187.8 35659.6 35676.0 34475.0 34509.8 34566.5 34681.1 34887.1 37108.3 37901.4 37939.2 38057.2 38226.6 33715.0 33748.7 33991.7 34443.5 34453.7 23841.9 25491.2 25535.7 25725.2 25808.7 18:41:49 INFO - PID 12416 | 18:41:49 INFO - PID 12416 | [#2] jquery.html Cycles:180 Average:34815.31 Median:12790.25 stddev:80219.96 (627.2%) stddev-sans-first:80403.75 18:41:49 INFO - PID 12416 | Values: 549.0 562.0 562.5 575.3 597.3 21785.0 22427.3 22537.3 22596.7 23242.2 25395.4 27260.7 27415.6 27444.6 27534.2 6483.3 6606.6 6624.6 6636.0 6699.2 6200.4 6444.5 6538.0 6545.4 6553.9 6537.7 6541.9 6543.6 6545.9 6546.7 6426.7 6499.2 6499.2 6511.5 6534.2 6008.2 6040.7 6055.4 6067.8 6075.9 27773.2 30655.4 31135.0 31198.5 31524.7 1915.9 1975.2 1978.6 1982.5 1990.5 19339.0 19573.8 19691.0 19723.2 19770.1 2285.3 2338.3 2376.0 2386.9 2398.9 140721.0 142986.0 145758.0 150966.0 151432.0 14285.9 15073.8 15166.0 15256.6 15289.9 23740.2 23839.8 23875.2 23896.9 23934.2 10159.6 10212.2 10220.8 10354.2 10389.0 9451.1 9540.4 9579.6 9624.0 9643.5 7624.2 8826.9 9206.4 9379.3 9604.7 2536.4 2626.3 2679.2 2759.0 2770.7 951.5 960.4 965.2 978.4 987.5 20381.1 20492.6 20864.0 21029.9 21227.6 1019.3 1040.1 1040.9 1052.3 1053.4 11164.3 11475.4 11493.7 11522.2 11616.1 461119.0 466219.0 475408.7 478074.0 484277.0 113042.0 113600.4 114942.0 115186.4 115237.8 1152.1 1199.2 1225.0 1236.1 1236.2 17799.2 18245.8 18342.8 21714.2 23069.1 55999.4 59969.0 61209.0 61719.4 64225.0 68434.3 69010.0 69028.7 69585.3 69977.7 12668.8 13164.1 14732.0 14776.9 15316.8 23559.2 23655.0 24014.9 24031.2 24120.5 18307.5 18694.7 18727.6 18798.8 18810.9 17083.7 17234.8 17357.3 17628.6 17817.7 18721.8 19389.0 19537.6 19600.7 19802.9 15000.9 15022.5 15067.7 15097.5 15127.0 18264.6 18657.8 19150.9 19167.1 19173.8 18:41:49 INFO - PID 12416 | 18:41:49 INFO - PID 12416 | [#3] mootools.html Cycles:180 Average:8643.79 Median:2629.13 stddev:5915.00 (225.0%) stddev-sans-first:5906.10 18:41:49 INFO - PID 12416 | Values: 1335.2 1406.9 1410.2 1419.2 1423.7 13486.2 13764.9 14051.8 14076.6 14094.3 14368.9 14576.9 14597.2 14620.9 14647.1 2620.2 2716.6 2773.0 2782.8 2784.5 2700.3 2731.4 2732.5 2740.0 2748.1 2667.7 2677.8 2732.2 2733.9 2734.0 2564.0 2638.0 2645.1 2647.0 2648.2 2586.6 2590.4 2604.4 2617.7 2655.2 13486.3 13792.7 13800.4 13846.9 13864.7 7495.3 7724.3 7729.1 7765.5 7786.1 4930.9 5246.7 5281.8 5319.8 5321.3 1750.1 1751.4 1770.6 1828.6 1837.3 22327.7 22530.8 22593.1 22710.4 22711.2 6439.7 7096.6 7150.7 7345.4 7345.6 15711.1 15848.1 15869.2 15877.8 15888.7 7506.3 7619.0 7633.7 7664.7 7675.8 6850.6 7063.1 7069.9 7243.7 7256.3 4977.7 5180.2 5189.5 5215.5 5278.0 1524.5 1526.1 1529.5 1549.8 1557.5 822.4 837.1 840.2 857.7 859.6 6616.3 6649.9 6689.1 6748.2 7377.7 939.7 944.4 946.6 948.9 954.1 4315.3 4660.0 4713.5 4715.9 4783.5 18749.3 19073.7 19363.5 19585.8 19703.9 18841.6 19015.3 19036.9 19210.0 19279.1 10509.3 10812.9 11054.3 11069.2 11086.1 7148.2 7282.0 7360.5 7383.0 7412.7 16361.2 16725.7 17169.1 17243.0 17249.0 17052.4 17058.7 17167.4 17316.1 17669.5 11171.1 11611.8 11701.0 11743.2 11744.6 13080.7 13088.2 13161.5 13238.5 13245.3 10779.2 10817.8 10983.1 11027.8 11045.6 10022.4 10138.2 10166.9 10191.6 10370.1 9419.8 9687.4 9700.5 9702.4 9710.1 6296.3 6324.6 6332.8 6360.8 6419.2 6560.2 6571.4 6628.3 6679.8 6712.1 18:41:49 INFO - PID 12416 | 18:41:49 INFO - PID 12416 | [#4] prototype.html Cycles:180 Average:5471.62 Median:6056.21 stddev:1546.18 (25.5%) stddev-sans-first:1504.50 18:41:49 INFO - PID 12416 | Values: 483.2 500.7 527.7 531.1 531.5 6194.7 6292.5 6306.6 6327.7 6363.5 5889.2 5964.4 5988.5 6054.8 6132.2 4817.5 4838.2 4881.8 4890.8 4902.3 4791.7 4938.0 4941.2 4953.6 4964.2 4862.1 4893.7 4898.9 4901.4 4911.6 4835.8 4881.2 4881.8 4900.7 4910.8 4344.9 4363.3 4382.7 4389.9 4405.1 6001.3 6029.3 6057.6 6064.9 6070.6 5632.2 5649.5 5657.7 5672.9 5681.3 6493.1 6494.6 6518.9 6553.2 6557.6 4804.6 4806.7 4829.9 4841.8 4858.6 6856.2 6886.8 6893.7 6911.3 6969.9 4963.6 4971.6 4972.5 4977.3 4986.6 4937.2 4938.8 5013.0 5036.4 5059.8 5664.5 5672.7 5687.7 5690.6 5696.5 6647.0 6659.5 6664.1 6664.5 6668.1 1616.7 1647.6 1684.1 1745.9 1750.1 1193.0 1210.4 1217.2 1219.4 1230.7 5780.5 5785.0 5792.6 5814.9 5840.3 6568.7 6602.5 6610.8 6675.5 6745.6 5741.7 5759.8 5795.2 5805.6 5859.4 3534.4 3552.2 3570.0 3590.6 3604.1 6467.7 6472.9 6515.5 6526.7 6540.4 6425.0 6846.2 6856.2 6869.9 6895.4 6401.3 6406.0 6428.1 6452.9 6487.9 6509.5 6558.5 6613.5 6621.4 6645.8 6489.9 6625.2 6638.9 6654.9 6685.1 6348.8 6349.5 6442.7 6456.1 6483.2 4869.3 4891.2 4899.6 4925.7 4927.2 6545.0 6696.9 6742.5 6767.2 6786.2 6376.8 6436.7 6454.8 6457.6 6519.9 6478.8 6541.0 6552.8 6557.6 6578.6 6303.4 6352.0 6402.4 6405.6 6450.3 6532.2 6563.2 6572.8 6576.7 6588.4 6319.7 6343.2 6354.0 6355.4 6369.2 18:41:49 INFO - PID 12416 | 18:41:49 INFO - PID 12416 | [#5] yui.html Cycles:180 Average:22877.18 Median:19421.85 stddev:30470.50 (156.9%) stddev-sans-first:30511.26 18:41:49 INFO - PID 12416 | Values: 892.6 917.9 918.8 925.4 927.4 17640.4 18623.9 19151.7 19174.6 19387.0 20491.7 20834.7 21074.8 21313.5 21463.7 1028.9 1047.9 1051.7 1054.9 1056.1 971.3 1040.6 1041.6 1048.4 1051.7 1089.8 1090.5 1091.1 1093.6 1093.7 1090.2 1091.5 1092.3 1095.9 1096.7 793.4 798.4 800.7 800.8 800.9 18953.4 19364.4 19416.8 19426.9 19722.0 20370.7 20465.9 20781.8 20903.3 20912.0 14828.4 14965.6 15076.0 15106.7 15183.3 3385.3 3461.0 3463.6 3463.8 3465.1 119634.0 120347.0 122952.4 130102.0 130853.0 16363.3 16602.2 17023.1 17032.7 17048.3 27234.3 30150.7 30820.4 30821.4 30849.8 8686.4 9090.5 9103.5 9166.1 9166.5 4094.1 4182.9 4185.7 4189.1 4200.5 5173.7 5319.0 5323.7 5335.4 5337.8 1016.5 1029.1 1047.1 1048.1 1058.9 658.6 677.0 685.9 694.8 700.0 11948.9 12074.5 12238.9 12537.6 12923.3 661.1 673.8 680.5 681.3 692.8 5297.8 5376.6 5385.0 5516.8 5531.9 96756.5 98851.0 103552.5 107453.0 108086.0 89746.1 89980.6 90449.0 90586.5 91742.5 77301.0 77592.4 82604.6 82947.0 83143.0 12476.0 12835.9 12896.2 13096.8 13253.9 36047.0 36729.8 36898.1 37390.6 38949.6 51040.0 64423.0 67525.3 68201.0 69261.0 14413.0 15275.9 16110.1 18249.4 18623.3 17464.7 19497.5 19683.9 19815.0 19877.7 18446.8 18818.2 18820.8 18959.9 19072.2 18865.5 18972.5 19129.9 19174.6 19313.1 18621.0 18636.3 18689.9 18706.5 18734.9 13792.2 13871.7 13965.4 14001.9 14056.4 14191.0 14212.9 14264.1 14286.6 14467.9 18:41:49 INFO - PID 12416 | -------- Summary: end -------- 18:41:49 INFO - PID 12416 | 18:41:50 INFO - TEST-INFO | 12416: exit 0 18:41:50 INFO - TEST-OK | dromaeo_css | took 1113368ms 18:41:50 INFO - TEST-START | kraken 18:41:50 INFO - Initialising browser for kraken test... 18:41:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56989/getInfo.html -profile /tmp/tmp8JjKlW/profile 18:41:50 INFO - TEST-INFO | started process 12789 (/builds/slave/test/build/application/firefox/firefox http://localhost:56989/getInfo.html) 18:41:56 INFO - TEST-INFO | 12789: exit 0 18:41:56 INFO - Browser initialized. 18:41:56 INFO - Running cycle 1/1 for kraken test... 18:41:56 INFO - TEST-INFO | started process 13014 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8JjKlW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:57 INFO - PID 13014 | 18:41:57 INFO - PID 13014 | (/builds/slave/test/build/application/firefox/firefox:13070): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:41:57 INFO - PID 13014 | 18:42:07 INFO - PID 13014 | RSS: Main: 174505984 18:42:07 INFO - PID 13014 | 18:42:10 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56989/tests/kraken/driver.html?testName=audio-beat-detection) 18:42:11 INFO - PID 13014 | RSS: Main: 179535872 18:42:11 INFO - PID 13014 | 18:42:14 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56989/tests/kraken/driver.html?testName=audio-dft) 18:42:14 INFO - PID 13014 | RSS: Main: 179998720 18:42:14 INFO - PID 13014 | 18:42:17 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56989/tests/kraken/driver.html?testName=audio-fft) 18:42:18 INFO - PID 13014 | RSS: Main: 174759936 18:42:18 INFO - PID 13014 | 18:42:20 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56989/tests/kraken/driver.html?testName=audio-oscillator) 18:42:21 INFO - PID 13014 | RSS: Main: 175132672 18:42:21 INFO - PID 13014 | 18:42:23 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56989/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:24 INFO - PID 13014 | RSS: Main: 175706112 18:42:24 INFO - PID 13014 | 18:42:27 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56989/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:27 INFO - PID 13014 | RSS: Main: 178040832 18:42:27 INFO - PID 13014 | 18:42:31 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56989/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:32 INFO - PID 13014 | RSS: Main: 177143808 18:42:32 INFO - PID 13014 | 18:42:34 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56989/tests/kraken/driver.html?testName=json-parse-financial) 18:42:35 INFO - PID 13014 | RSS: Main: 178302976 18:42:35 INFO - PID 13014 | 18:42:37 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56989/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:42:37 INFO - PID 13014 | RSS: Main: 178262016 18:42:37 INFO - PID 13014 | 18:42:39 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56989/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:40 INFO - PID 13014 | RSS: Main: 174960640 18:42:40 INFO - PID 13014 | 18:42:42 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56989/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:42:43 INFO - PID 13014 | RSS: Main: 175284224 18:42:43 INFO - PID 13014 | 18:42:46 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:42:46 INFO - PID 13014 | RSS: Main: 175546368 18:42:46 INFO - PID 13014 | 18:42:49 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:42:50 INFO - PID 13014 | RSS: Main: 175796224 18:42:50 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | Cycle 1(1): loaded http://localhost:56989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56989/tests/kraken/driver.html?testName=ai-astar) 18:42:52 INFO - PID 13014 | RSS: Main: 177410048 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | __start_tp_report 18:42:52 INFO - PID 13014 | _x_x_mozilla_page_load 18:42:52 INFO - PID 13014 | _x_x_mozilla_page_load_details 18:42:52 INFO - PID 13014 | |i|pagename|runs| 18:42:52 INFO - PID 13014 | |0;ai-astar;105;109;105;107;119;110;118;109;111;109 18:42:52 INFO - PID 13014 | |1;audio-beat-detection;144;120;148;174;123;149;150;120;169;124 18:42:52 INFO - PID 13014 | |2;audio-dft;165;164;170;163;164;163;173;175;165;163 18:42:52 INFO - PID 13014 | |3;audio-fft;74;74;77;175;74;73;74;77;147;75 18:42:52 INFO - PID 13014 | |4;audio-oscillator;78;79;138;79;82;84;129;78;79;127 18:42:52 INFO - PID 13014 | |5;imaging-gaussian-blur;114;114;114;114;113;118;115;116;117;115 18:42:52 INFO - PID 13014 | |6;imaging-darkroom;152;152;151;152;151;150;151;150;152;150 18:42:52 INFO - PID 13014 | |7;imaging-desaturate;95;94;94;94;95;94;94;94;95;97 18:42:52 INFO - PID 13014 | |8;json-parse-financial;55;60;54;54;57;54;57;55;58;55 18:42:52 INFO - PID 13014 | |9;json-stringify-tinderbox;54;53;54;54;55;61;54;54;57;59 18:42:52 INFO - PID 13014 | |10;stanford-crypto-aes;79;95;79;90;96;89;80;77;78;81 18:42:52 INFO - PID 13014 | |11;stanford-crypto-ccm;131;136;125;124;129;126;140;134;127;165 18:42:52 INFO - PID 13014 | |12;stanford-crypto-pbkdf2;186;183;183;175;180;180;179;172;187;186 18:42:52 INFO - PID 13014 | |13;stanford-crypto-sha256-iterative;67;66;67;65;65;67;70;67;71;76 18:42:52 INFO - PID 13014 | __end_tp_report 18:42:52 INFO - PID 13014 | __start_cc_report 18:42:52 INFO - PID 13014 | _x_x_mozilla_cycle_collect,979 18:42:52 INFO - PID 13014 | __end_cc_report 18:42:52 INFO - PID 13014 | __startTimestamp1501724572686__endTimestamp 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | ------- Summary: start ------- 18:42:52 INFO - PID 13014 | Number of tests: 14 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#0] ai-astar Cycles:10 Average:110.20 Median:109.50 stddev:4.80 (4.4%) stddev-sans-first:4.71 18:42:52 INFO - PID 13014 | Values: 105.0 109.0 105.0 107.0 119.0 110.0 118.0 109.0 111.0 109.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#1] audio-beat-detection Cycles:10 Average:142.10 Median:148.50 stddev:19.89 (13.4%) stddev-sans-first:21.08 18:42:52 INFO - PID 13014 | Values: 144.0 120.0 148.0 174.0 123.0 149.0 150.0 120.0 169.0 124.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#2] audio-dft Cycles:10 Average:166.50 Median:165.00 stddev:4.48 (2.7%) stddev-sans-first:4.72 18:42:52 INFO - PID 13014 | Values: 165.0 164.0 170.0 163.0 164.0 163.0 173.0 175.0 165.0 163.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#3] audio-fft Cycles:10 Average:92.00 Median:74.00 stddev:36.98 (50.0%) stddev-sans-first:38.65 18:42:52 INFO - PID 13014 | Values: 74.0 74.0 77.0 175.0 74.0 73.0 74.0 77.0 147.0 75.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:79.00 stddev:25.09 (31.8%) stddev-sans-first:25.82 18:42:52 INFO - PID 13014 | Values: 78.0 79.0 138.0 79.0 82.0 84.0 129.0 78.0 79.0 127.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:115.00 stddev:1.56 (1.4%) stddev-sans-first:1.62 18:42:52 INFO - PID 13014 | Values: 114.0 114.0 114.0 114.0 113.0 118.0 115.0 116.0 117.0 115.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:151.50 stddev:0.88 (0.6%) stddev-sans-first:0.87 18:42:52 INFO - PID 13014 | Values: 152.0 152.0 151.0 152.0 151.0 150.0 151.0 150.0 152.0 150.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.50 stddev:0.97 (1.0%) stddev-sans-first:1.01 18:42:52 INFO - PID 13014 | Values: 95.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 95.0 97.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:2.02 (3.6%) stddev-sans-first:2.12 18:42:52 INFO - PID 13014 | Values: 55.0 60.0 54.0 54.0 57.0 54.0 57.0 55.0 58.0 55.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#9] json-stringify-tinderbox Cycles:10 Average:55.50 Median:54.50 stddev:2.64 (4.8%) stddev-sans-first:2.74 18:42:52 INFO - PID 13014 | Values: 54.0 53.0 54.0 54.0 55.0 61.0 54.0 54.0 57.0 59.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:7.34 (8.6%) stddev-sans-first:7.52 18:42:52 INFO - PID 13014 | Values: 79.0 95.0 79.0 90.0 96.0 89.0 80.0 77.0 78.0 81.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#11] stanford-crypto-ccm Cycles:10 Average:133.70 Median:132.50 stddev:12.15 (9.2%) stddev-sans-first:12.85 18:42:52 INFO - PID 13014 | Values: 131.0 136.0 125.0 124.0 129.0 126.0 140.0 134.0 127.0 165.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.10 Median:183.00 stddev:4.91 (2.7%) stddev-sans-first:4.88 18:42:52 INFO - PID 13014 | Values: 186.0 183.0 183.0 175.0 180.0 180.0 179.0 172.0 187.0 186.0 18:42:52 INFO - PID 13014 | 18:42:52 INFO - PID 13014 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:67.00 stddev:3.38 (5.0%) stddev-sans-first:3.56 18:42:52 INFO - PID 13014 | Values: 67.0 66.0 67.0 65.0 65.0 67.0 70.0 67.0 71.0 76.0 18:42:52 INFO - PID 13014 | -------- Summary: end -------- 18:42:52 INFO - PID 13014 | 18:42:53 INFO - PID 13014 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 18:42:53 INFO - TEST-INFO | 13014: exit 0 18:42:53 INFO - TEST-OK | kraken | took 63263ms 18:42:53 INFO - SUITE-END | took 1176s 18:42:53 INFO - Completed test suite (00:19:37) 18:42:53 INFO - javascript benchmark 18:42:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8452.244456584995, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.4025635129324, 3147.9370412591747, 3148.79532830366, 3181.061586831884, 3199.4880819068953, 6172.074188871671, 6199.690015499224, 6223.875522489548, 6244.3755624437645, 6266.373362663731, 6138.447539721425, 6180.41286077823, 6217.222847144107, 6243.250809902806, 6258.123862659231, 5649.096144616862, 5668.943310566903, 5690.857728556778, 5698.259226300575, 5707.315122185343, 5693.316801983747, 5696.430356964291, 5716.456936591017, 5720.79977200798, 5724.2558467399385, 5665.773369065232, 5682.147677848315, 5687.914681279784, 5717.685527295997, 5798.594098413092, 5771.3074431068335, 5779.422057794229, 5779.45095215958, 5796.50729687977, 5836.6789826559525, 5636.210930469738, 5668.829935101912, 5717.113847353667, 5733.283339582552, 5736.368999410061, 5928.49607783339, 6010.188624535675, 6029.8191054268445, 6033.788817391414, 6135.417135372132, 6647.966760166217, 6715.899261511081, 6738.393544580962, 6751.189857217117, 6818.352256535622, 6747.359000894907, 6783.084283621746, 6813.7955861324235, 6836.316368363174, 6871.896921546131, 6206.472449841724, 6254.21822272216, 6259.155014073087, 6273.027680709497, 6345.555811093225, 6860.0738900248825, 6898.206706228848, 6922.723091076401, 6923.6884340204815, 6930.480213983971, 6602.73589056442, 6608.933910660928, 6627.867442651216, 6646.734130634721, 6648.66756662165, 6614.867702646016, 6647.4682025438815, 6657.3342665733035, 6674.199096108498, 6741.494387920926, 6628.5360024798765, 6657.201135863727, 6710.832229194211, 6716.764913228014, 6745.426638735665, 6621.205455345389, 6648.833779155463, 6684.86630267392, 6687.732490700415, 6784.796456106226, 5262.684238945675, 5266.8946621068135, 5295.867603309948, 5384.326959130109, 5400.081986062379, 4599.103174880933, 4653.255479123324, 4660.231061874752, 4671.76641167947, 4744.644151688568, 5819.010768169422, 5837.766489340379, 5920.348761636216, 5924.14099955504, 5933.050711886078, 6590.0, 6691.631960242136, 6707.731690732316, 6715.328467153246, 6748.156480439945, 5736.139579063174, 5824.242848429677, 5831.300243970751, 5833.125031245262, 5836.124581312837, 5764.769409223584, 5768.942310576925, 5858.912116318259, 5859.94140058594, 5869.060950248026, 6429.678516074177, 6492.64290464029, 6502.479801615859, 6506.056621789909, 6509.218893732783, 6658.400743933069, 6685.76599819004, 6763.458923286027, 6777.999999999902, 6781.694823732845, 6325.778597749057, 6426.807195784134, 6433.517486188648, 6438.581492202992, 6452.12896258999, 6555.4427873631275, 6566.6388348641285, 6569.4744420445295, 6577.0, 6604.9009264862025, 6673.799786006428, 6701.731930722716, 6703.966480167568, 6735.090762746966, 6780.69486873082, 6420.711068002044, 6430.324815894451, 6487.351264873475, 6494.740210391531, 6506.219253689587, 6425.967870160806, 6496.447801936699, 6506.056621789909, 6518.120053792677, 6539.542232043711, 6335.556511044183, 6373.171487706569, 6438.066480360228, 6440.806775796546, 6453.128827608213, 6528.118703974903, 6544.280129185881, 6563.0, 6597.868042639025, 6652.201735791736, 6204.348543402878, 6260.186175797117, 6283.937160628335, 6420.903686444801, 6436.324185960407], "value": 6097.199828039614, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.2490233887164, 839.6221700234896, 867.648602316062, 888.8933328000645, 891.3448615267777, 40087.39868901969, 40759.796201019024, 40787.18425631485, 41013.7949310253, 41204.79397603015, 37064.81467592665, 37328.440073398924, 37486.625133748654, 37638.811805941, 37731.24537509255, 6661.90007149892, 6793.252742198354, 6823.69293381799, 6828.248892621832, 6839.1451068616425, 6668.399844014038, 6777.593344399327, 6779.661016949158, 6780.423663988567, 6785.016172654962, 6749.831254218635, 7299.927000730005, 7579.81050473737, 7593.582352970584, 7598.202188770182, 7486.812829679275, 7498.850022999564, 7527.510711803742, 7528.811779705469, 7577.659005344718, 7226.855462890713, 7366.079240094989, 7371.88942165868, 7382.630868456556, 7509.4743367964265, 39213.803930980444, 39252.21495570073, 39268.60731392678, 39303.0, 39425.21149576992, 3552.413851714464, 3601.2617413430185, 3615.493830863681, 3620.31214069326, 3627.256412435471, 31847.0, 31855.203619909455, 31938.361232775445, 31945.0, 31987.52018719721, 4703.341532185496, 4811.254255590389, 4895.339129217546, 4906.141425250603, 4912.631552633531, 188904.0, 191404.0, 191979.0, 193432.0, 196783.0160849201, 50152.749236253585, 50454.747726261136, 50570.98858022893, 50673.74663126661, 50759.746201268754, 51884.74057629688, 51994.48005519972, 52001.219981700306, 52007.0, 52146.47853521492, 16014.679706405806, 16132.67734645324, 16193.271302791403, 16195.757063644054, 16293.674126517404, 13094.869051309555, 13102.082854200115, 13102.279374634296, 13162.473501060042, 13218.867811321956, 14697.118172909446, 14702.55892323232, 14757.852421475647, 14773.33519991603, 15059.096454212782, 5689.175069614883, 5718.771249150071, 5830.017793950077, 5850.896508335985, 5874.766299077156, 978.7406337320616, 981.2052237687496, 1013.7110923386799, 1015.8628585141059, 1017.6949210039267, 23062.8846855768, 23261.418464538183, 23400.414989625395, 23478.413039674146, 23691.526169476514, 1113.287496002559, 1114.3815182573587, 1116.3636727065496, 1118.8321751737142, 1120.6974116988367, 12515.499380024698, 12577.748445031231, 12579.874201258053, 12586.874131258754, 12609.810852837214, 214803.0, 215087.0, 215276.0, 215955.0, 216482.0, 53711.0, 53948.73025634847, 54115.72942135264, 54247.0, 54248.0, 1419.3683810704138, 1468.919209443491, 1487.732208202534, 1493.1936754152637, 1494.8505149485181, 22940.541189176456, 22960.0, 23052.193173238855, 23402.882985585646, 23455.88272058697, 38905.221895561364, 39011.219775603764, 39188.41217381799, 39214.607853921094, 39229.215415692095, 192273.0, 193161.0, 193194.0, 193432.0, 194117.0, 30122.548161778028, 30714.692853071185, 30887.0, 31272.06183814489, 34031.0, 42631.147377052905, 44170.0, 44783.328250075625, 45018.77490612657, 45369.54630453785, 34627.65372346345, 35129.82435087808, 35187.82406087953, 35659.643403565635, 35676.0, 34475.0, 34509.827450862584, 34566.48150277697, 34681.13297167591, 34887.12782180466, 37108.257834843695, 37901.43147852839, 37939.24121517499, 38057.23885522219, 38226.617733822306, 33715.0, 33748.662513374555, 33991.66008339984, 34443.4833477493, 34453.65546344605, 23841.880790595937, 25491.23526294214, 25535.723213839607, 25725.228243152735, 25808.741912580634], "value": 17153.959321751438, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [548.9698066606335, 562.0483540012493, 562.4544192133632, 575.3239943066897, 597.271328978646, 21785.0, 22427.32718018458, 22537.323880283577, 22596.66105008424, 23242.186523471682, 25395.36511587211, 27260.72739272607, 27415.588766168523, 27444.588331175048, 27534.173974780737, 6483.286838447767, 6606.570572912769, 6624.635645039521, 6635.966820165892, 6699.229588597301, 6200.410960958701, 6444.484441244689, 6537.967310163443, 6545.443637290836, 6553.934460655404, 6537.7384904604, 6541.901871471931, 6543.607383556978, 6545.934540654604, 6546.738130474775, 6426.742930282807, 6499.155109835691, 6499.155109835739, 6511.511636627272, 6534.183227096613, 6008.158857759917, 6040.697965101727, 6055.364186760415, 6067.787627433063, 6075.939240607581, 27773.166804995883, 30655.386892262253, 31135.0, 31198.532022019688, 31524.684753152633, 1915.9425217243504, 1975.2099160335838, 1978.574606459618, 1982.4944639117077, 1990.4725247809342, 19339.0, 19573.804261957484, 19691.0, 19723.21107155712, 19770.110345034507, 2285.2915596165244, 2338.3101984914492, 2375.9524809503887, 2386.8925898334614, 2398.8680622565753, 140721.0, 142986.0, 145758.0, 150966.0, 151432.0, 14285.85714142866, 15073.849261507245, 15166.0, 15256.618584535254, 15289.923550382176, 23740.169094081968, 23839.7616023841, 23875.16436924699, 23896.88051559731, 23934.162304319267, 10159.644412445678, 10212.183025357954, 10220.795584088277, 10354.223433242538, 10389.0, 9451.149396554343, 9540.427574345562, 9579.616815327308, 9624.0, 9643.517824108767, 7624.19945905683, 8826.91173088261, 9206.447613143233, 9379.296552758571, 9604.66383676568, 2536.4039450729065, 2626.338249606925, 2679.196241127654, 2759.0, 2770.7229277072133, 951.5432592355708, 960.4285450157146, 965.2480747443368, 978.4031740638205, 987.4865070163474, 20381.082851271727, 20492.590148196952, 20864.0, 21029.89485052565, 21227.575448490945, 1019.3170575714289, 1040.0795296162798, 1040.8959104089531, 1052.2686732720686, 1053.4153544782719, 11164.330140191614, 11475.368854712904, 11493.655190344303, 11522.193446458667, 11616.128790340757, 461119.0, 466219.0, 475408.69114074175, 478074.0, 484277.0, 113042.0, 113600.43199783948, 114942.0, 115186.42406787912, 115237.84762152439, 1152.124385467051, 1199.1785626845638, 1224.9755004899853, 1236.0544183699476, 1236.1964722930168, 17799.19903604367, 18245.817541824414, 18342.81657183411, 21714.24000159987, 23069.077236910336, 55999.44000560105, 59969.0, 61209.0, 61719.382806171365, 64225.036624451604, 68434.3156568428, 69010.0, 69028.65485672539, 69585.30414695991, 69977.65011175115, 12668.809967850306, 13164.14433061848, 14732.0, 14776.891733119845, 15316.770248446504, 23559.175428859908, 23655.0, 24014.87992560026, 24031.158909438087, 24120.517674560688, 18307.450776476726, 18694.719579206052, 18727.625447491246, 18798.812011880254, 18810.905945470186, 17083.743743843603, 17234.82765172332, 17357.30570777155, 17628.647427051645, 17817.73273400926, 18721.81278187255, 19389.0, 19537.60924781468, 19600.705989409886, 19802.90098549498, 15000.924995374953, 15022.549323520312, 15067.698646026798, 15097.47158849435, 15127.0, 18264.634707305515, 18657.81342186561, 19150.904245478683, 19167.137478813765, 19173.808261917202], "value": 12125.9343405019, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.198880671597, 1406.94372225111, 1410.1961881727416, 1419.2123371528796, 1423.6867889064401, 13486.190828550294, 13764.931175344134, 14051.789223161648, 14076.64808379791, 14094.295285235736, 14368.856311436883, 14576.854231457683, 14597.197154156516, 14620.926895365536, 14647.121172729618, 2620.2401303621928, 2716.633254510645, 2773.0294396961003, 2782.7634651054686, 2784.4570308789794, 2700.2844246274753, 2731.426400455907, 2732.508148533273, 2740.0409856550245, 2748.0656576763895, 2667.7465640764194, 2677.759001689857, 2732.221316924677, 2733.931651708713, 2734.0, 2563.974360256411, 2638.023931145479, 2645.1271080543374, 2647.0470630573072, 2648.1923013480978, 2586.599077143027, 2590.378309205796, 2604.414006848463, 2617.7251388604304, 2655.216711070239, 13486.258255795927, 13792.724145517033, 13800.447982080805, 13846.861531384759, 13864.722705545933, 7495.287947644965, 7724.266194711549, 7729.111152217454, 7765.456418050739, 7786.065672119325, 4930.852074437773, 5246.658967167122, 5281.788728450857, 5319.813806516792, 5321.308229930123, 1750.1424302092012, 1751.448293787453, 1770.5662112782322, 1828.570285982793, 1837.3110083718605, 22327.66508502374, 22530.77469225303, 22593.096276148935, 22710.432239194153, 22711.20510782131, 6439.710213040425, 7096.574205547684, 7150.678219480136, 7345.375643070399, 7345.632718364061, 15711.135887526067, 15848.1283529407, 15869.20653967297, 15877.761833572506, 15888.682226355408, 7506.28690274423, 7618.961905190438, 7633.69465221385, 7664.65509052094, 7675.808104797425, 6850.588964662036, 7063.081799366049, 7069.858602827914, 7243.674034668453, 7256.2743725627015, 4977.676451030671, 5180.171172612364, 5189.481051894781, 5215.452377500384, 5277.973610131925, 1524.4740564505228, 1526.0538466151058, 1529.5029115537495, 1549.775282584019, 1557.5171696774037, 822.3708862720024, 837.1377481194379, 840.1682334488812, 857.6955180910811, 859.5616235719828, 6616.27221005689, 6649.867002659919, 6689.130413046273, 6748.190217173872, 7377.704891804267, 939.7321763297427, 944.4474982135528, 946.6024269806697, 948.9145976862116, 954.1126752120595, 4315.3095504719095, 4660.044690838437, 4713.457952335525, 4715.929261061086, 4783.545563171493, 18749.34377296788, 19073.713894291595, 19363.51591210231, 19585.804141958684, 19703.901480492506, 18841.623167536847, 19015.33446329372, 19036.904815475835, 19210.0, 19279.132439040277, 10509.264351495474, 10812.891871081345, 11054.281471704475, 11069.169812264112, 11086.057685096695, 7148.213696493278, 7281.955976539565, 7360.521566098187, 7383.0, 7412.740554080581, 16361.181940902668, 16725.749113763548, 17169.141542923055, 17243.0, 17249.0, 17052.403165889133, 17058.744118837978, 17167.399141030004, 17316.134193290538, 17669.469915902544, 11171.106311495223, 11611.825822612498, 11701.0, 11743.17797754149, 11744.58893938708, 13080.73838523205, 13088.214707117604, 13161.47354105825, 13238.470461181445, 13245.337733113498, 10779.191560632828, 10817.837732434176, 10983.121350291798, 11027.779444411228, 11045.558177672803, 10022.398656080659, 10138.188944884245, 10166.949165254422, 10191.643292484727, 10370.066693997576, 9419.811603768023, 9687.41875487473, 9700.466474343979, 9702.369345992487, 9710.126088652056, 6296.307406185408, 6324.557280990287, 6332.778352757631, 6360.809175724736, 6419.197600299963, 6560.212774466903, 6571.408573228432, 6628.304028077176, 6679.76620818269, 6712.060311556288], "value": 6310.168381932557, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.16653772242887, 500.70458429526593, 527.6596595196098, 531.1422053383782, 531.54552857307, 6194.7212375443105, 6292.46514046306, 6306.558540902127, 6327.683615819214, 6363.490920726336, 5889.234399528055, 5964.3617393389995, 5988.490978266842, 6054.788082417116, 6132.1721567588365, 4817.494163112875, 4838.177509823338, 4881.755912204393, 4890.828820991266, 4902.264660300948, 4791.736454495001, 4937.975310123445, 4941.2094064949615, 4953.628477864157, 4964.180910149821, 4862.086020753292, 4893.730844803535, 4898.902021959559, 4901.436334821487, 4911.607071434295, 4835.758212089417, 4881.170201065827, 4881.755912204376, 4900.681455705403, 4910.8035678573, 4344.89137771555, 4363.3454981752675, 4382.737035777864, 4389.912201755979, 4405.096955124224, 6001.309849367335, 6029.276486821565, 6057.636541807506, 6064.9090263646085, 6070.605410648338, 5632.155176723524, 5649.548036157097, 5657.745401456944, 5672.9432705672825, 5681.289838770153, 6493.123428337209, 6494.610323380565, 6518.924377477758, 6553.180852393451, 6557.606543607399, 4804.567588917014, 4806.735629540374, 4829.855104346875, 4841.830535931225, 4858.587020103296, 6856.177258728934, 6886.763221285987, 6893.689783959633, 6911.308869113099, 6969.884818429077, 4963.60291176705, 4971.577415919687, 4972.5276098770555, 4977.278294647256, 4986.601071914238, 4937.23472861707, 4938.802447902043, 5012.974935125301, 5036.395632524121, 5059.822906198266, 5664.498907789439, 5672.659640421588, 5687.744051517691, 5690.573207009491, 5696.458836410535, 6647.0029495576055, 6659.53383263167, 6664.133662623828, 6664.5334826561675, 6668.133142691516, 1616.7089923813592, 1647.5963388969778, 1684.0990070312569, 1745.930162793474, 1750.0812073661175, 1192.9880701193051, 1210.3706072842074, 1217.2392254840724, 1219.426869371394, 1230.7476967221698, 5780.537556995514, 5784.958707432703, 5792.594518383757, 5814.883702326015, 5840.29916410025, 6568.737250510022, 6602.537822352389, 6610.7686230982645, 6675.46596272297, 6745.5952642841585, 5741.712914354265, 5759.798407055733, 5795.217645617872, 5805.564582656318, 5859.384764599741, 3534.3991521441417, 3552.2362692020824, 3570.0, 3590.551181102362, 3604.116991337087, 6467.708953097122, 6472.870542589122, 6515.4787616990525, 6526.706298216592, 6540.444062254762, 6424.967875160688, 6846.246912839583, 6856.211535673362, 6869.931300686929, 6895.4138898194215, 6401.295857455583, 6405.967970160119, 6428.100065990765, 6452.870942581122, 6487.935120648733, 6509.4792416605615, 6558.475321974326, 6613.537052406477, 6621.370969758058, 6645.800625981228, 6489.902651460137, 6625.17185351831, 6638.90041649366, 6654.90017649726, 6685.130932978682, 6348.809535713936, 6349.49204063683, 6442.742290308523, 6456.094757359113, 6483.157189565326, 4869.269609558594, 4891.241857512091, 4899.608031357411, 4925.684928083795, 4927.187014142626, 6544.985527243285, 6696.933030669631, 6742.460603151638, 6767.221769496571, 6786.21958474762, 6376.776812811529, 6436.67816609177, 6454.806355809334, 6457.580257283262, 6519.869602607826, 6478.805635830932, 6540.967295163494, 6552.803415897531, 6557.639329836905, 6578.605283682994, 6303.352591911316, 6351.96824015877, 6402.439024390281, 6405.551611387158, 6450.322716114741, 6532.150820393318, 6563.1796025496815, 6572.8356791080605, 6576.731027795403, 6588.407043365928, 6319.747210111544, 6343.175387199634, 6353.96823015882, 6355.396237357444, 6369.20384951881], "value": 5016.308822315613, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.593869789246, 917.8898532176138, 918.7519369770166, 925.3938670171036, 927.4157280913024, 17640.38258660947, 18623.906880465594, 19151.712724309113, 19174.616507669874, 19387.0, 20491.69262461064, 20834.68747968778, 21074.789252107475, 21313.467163320925, 21463.67804482934, 1028.8611037509952, 1047.8690163729534, 1051.6582110813977, 1054.910332621728, 1056.0706578211164, 971.2861047130353, 1040.604570263299, 1041.6093964763213, 1048.444324508008, 1051.7002654243543, 1089.84742136101, 1090.487470888681, 1091.0943916549286, 1093.6227001684406, 1093.7046997310722, 1090.1823632275793, 1091.5360971587083, 1092.2900114925283, 1095.901368876797, 1096.7313008312935, 793.4089103617841, 798.3652995868299, 800.6837299266815, 800.8358286551243, 800.8518424091561, 18953.43139705811, 19364.419067428, 19416.805831941783, 19426.902865485583, 19722.0, 20370.694439583418, 20465.897670511553, 20781.792182078138, 20903.26838560658, 20912.0, 14828.406863725439, 14965.625859353495, 15076.040114785117, 15106.697866042727, 15183.316750746133, 3385.289089291252, 3461.0309113448275, 3463.5670541182353, 3463.8441270142903, 3465.064432603183, 119634.0, 120347.0, 122952.38523807413, 130102.0, 130853.0, 16363.263653135638, 16602.169891505375, 17023.14884255782, 17032.744508832377, 17048.318067277418, 27234.319142021606, 30150.69849301523, 30820.383592328475, 30821.383572328876, 30849.84575077155, 8686.435381700296, 9090.500022498694, 9103.544822758968, 9166.129217724305, 9166.495842728715, 4094.0788322627172, 4182.895427614334, 4185.707000509996, 4189.05746207107, 4200.4959404871, 5173.715445650525, 5319.042572336939, 5323.653962492503, 5335.413104558495, 5337.8131765388025, 1016.5425558498705, 1029.1098200056897, 1047.1151876664208, 1048.0672201740458, 1058.8623478947688, 658.5818005566404, 676.9695363708645, 685.8971154326891, 694.833240022391, 700.0409439068509, 11948.880511194951, 12074.517019319304, 12238.925235978695, 12537.623871283877, 12923.289219092852, 661.1240106858431, 673.7574473189648, 680.4896327754184, 681.2846511163259, 692.8025512728949, 5297.841064768063, 5376.596755243294, 5384.968834904931, 5516.7517461714315, 5531.944680553143, 96756.51621741986, 98851.0, 103552.48223758832, 107453.0, 108086.0, 89746.10253897509, 89980.5500972491, 90449.0, 90586.54706726424, 91742.54128729313, 77301.0, 77592.44815103666, 82604.58697706593, 82947.0, 83143.0, 12476.0, 12835.892514395304, 12896.226226426445, 13096.803547946978, 13253.933730331286, 36047.0, 36729.816350918074, 36898.07754806151, 37390.62609373871, 38949.61050389573, 51039.95840166352, 64423.0, 67525.32474675191, 68201.0, 69261.0, 14413.0, 15275.923620381827, 16110.113943733208, 18249.361272355403, 18623.348182813537, 17464.65070698604, 19497.512562186057, 19683.901580492005, 19815.0, 19877.701834472206, 18446.815531845048, 18818.15318310652, 18820.811791881904, 18959.90520047391, 19072.237110515423, 18865.528361791065, 18972.525686857938, 19129.90435047816, 19174.61650766949, 19313.130909108844, 18621.0, 18636.34772782946, 18689.90655046716, 18706.532336691147, 18734.906325468284, 13792.172469651852, 13871.722565548835, 13965.371558280105, 14001.859981400463, 14056.437742490594, 14191.0, 14212.92893535567, 14264.144151350953, 14286.642833929234, 14467.855321447072], "value": 9081.754998998116, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 109.0, 105.0, 107.0, 119.0, 110.0, 118.0, 109.0, 111.0, 109.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 120.0, 148.0, 174.0, 123.0, 149.0, 150.0, 120.0, 169.0, 124.0], "value": 142.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 164.0, 170.0, 163.0, 164.0, 163.0, 173.0, 175.0, 165.0, 163.0], "value": 166.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 77.0, 175.0, 74.0, 73.0, 74.0, 77.0, 147.0, 75.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 138.0, 79.0, 82.0, 84.0, 129.0, 78.0, 79.0, 127.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 114.0, 113.0, 118.0, 115.0, 116.0, 117.0, 115.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 152.0, 151.0, 152.0, 151.0, 150.0, 151.0, 150.0, 152.0, 150.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 95.0, 97.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 60.0, 54.0, 54.0, 57.0, 54.0, 57.0, 55.0, 58.0, 55.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 54.0, 54.0, 55.0, 61.0, 54.0, 54.0, 57.0, 59.0], "value": 55.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 95.0, 79.0, 90.0, 96.0, 89.0, 80.0, 77.0, 78.0, 81.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 136.0, 125.0, 124.0, 129.0, 126.0, 140.0, 134.0, 127.0, 165.0], "value": 133.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 183.0, 175.0, 180.0, 180.0, 179.0, 172.0, 187.0, 186.0], "value": 181.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 65.0, 65.0, 67.0, 70.0, 67.0, 71.0, 76.0], "value": 68.1, "unit": "score"}]}]} 18:42:53 INFO - Return code: 0 18:42:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:42:53 INFO - # TBPL SUCCESS # 18:42:53 INFO - Running post-action listener: _package_coverage_data 18:42:53 INFO - Running post-action listener: _resource_record_post_action 18:42:53 INFO - [mozharness: 2017-08-03 01:42:53.949356Z] Finished run-tests step (success) 18:42:53 INFO - Running post-run listener: _resource_record_post_run 18:42:54 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 439922688; Read time: 16; Write time: 365796 18:42:54 INFO - TinderboxPrint: CPU usage
13.2% 18:42:54 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 18:42:54 INFO - TinderboxPrint: I/O write bytes / time
439,922,688 / 365,796 18:42:54 INFO - TinderboxPrint: CPU idle
8,270.9 (86.8%) 18:42:54 INFO - TinderboxPrint: CPU user
1,205.8 (12.7%) 18:42:54 INFO - TinderboxPrint: Swap in / out
0 / 0 18:42:54 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116019200; Read time: 0; Write time: 138976 18:42:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:42:54 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 323489792; Read time: 0; Write time: 226792 18:42:54 INFO - Running post-run listener: _upload_blobber_files 18:42:54 INFO - Blob upload gear active. 18:42:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:42:54 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 18:42:54 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'] 18:42:54 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 18:42:55 INFO - (blobuploader) - INFO - Open directory for files ... 18:42:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:42:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:42:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:56 INFO - (blobuploader) - INFO - Done attempting. 18:42:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:42:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:42:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:57 INFO - (blobuploader) - INFO - Done attempting. 18:42:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:42:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:43:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:00 INFO - (blobuploader) - INFO - Done attempting. 18:43:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:43:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:43:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:43:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:43:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:43:00 INFO - (blobuploader) - INFO - Done attempting. 18:43:00 INFO - (blobuploader) - INFO - Iteration through files over. 18:43:00 INFO - Return code: 0 18:43:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:43:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:43:00 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c42a402a57217e090185bc18dfe62b812f7f590f7ddfc6f5f713daff52d971aeddbafde803f7db996c138efa60e13a5beeb8ea25a66e12197525c8a9935e7ded", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55d3cd5cc3430b55a7cc48b8b95fe20df1d270ddf2b047946aba296b7ebad22c45fa7eba9c2a14a4f5f0e87e3f90772a3cbd78b60582933b54a2bbf50c90605", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e4c650eaacdc9515bbdb09f056dcefd831c8ca3804d941f9c9241ebcba09ff2dd404662265efabb277f14f242bf7037a93dad2aeb95f28e7aff3ac7887bf3a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd48c033599f4c0f6dc334893b1366ade3f2afa6242c96cc05f1091016476fe518ee6325277514136135fec5fdca4613fc34eb779cc2366d6d63c6e633c9b6f8"} 18:43:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:43:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:43:00 INFO - Contents: 18:43:00 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c42a402a57217e090185bc18dfe62b812f7f590f7ddfc6f5f713daff52d971aeddbafde803f7db996c138efa60e13a5beeb8ea25a66e12197525c8a9935e7ded", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55d3cd5cc3430b55a7cc48b8b95fe20df1d270ddf2b047946aba296b7ebad22c45fa7eba9c2a14a4f5f0e87e3f90772a3cbd78b60582933b54a2bbf50c90605", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e4c650eaacdc9515bbdb09f056dcefd831c8ca3804d941f9c9241ebcba09ff2dd404662265efabb277f14f242bf7037a93dad2aeb95f28e7aff3ac7887bf3a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd48c033599f4c0f6dc334893b1366ade3f2afa6242c96cc05f1091016476fe518ee6325277514136135fec5fdca4613fc34eb779cc2366d6d63c6e633c9b6f8"} 18:43:00 INFO - Running post-run listener: copy_logs_to_upload_dir 18:43:00 INFO - Copying logs to upload dir... 18:43:00 INFO - mkdir: /builds/slave/test/build/upload/logs 18:43:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.943854 ========= master_lag: -0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-08-02 18:43:00.675026) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 18:43:00.683527) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240 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/c42a402a57217e090185bc18dfe62b812f7f590f7ddfc6f5f713daff52d971aeddbafde803f7db996c138efa60e13a5beeb8ea25a66e12197525c8a9935e7ded", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55d3cd5cc3430b55a7cc48b8b95fe20df1d270ddf2b047946aba296b7ebad22c45fa7eba9c2a14a4f5f0e87e3f90772a3cbd78b60582933b54a2bbf50c90605", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e4c650eaacdc9515bbdb09f056dcefd831c8ca3804d941f9c9241ebcba09ff2dd404662265efabb277f14f242bf7037a93dad2aeb95f28e7aff3ac7887bf3a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd48c033599f4c0f6dc334893b1366ade3f2afa6242c96cc05f1091016476fe518ee6325277514136135fec5fdca4613fc34eb779cc2366d6d63c6e633c9b6f8"} build_url:https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/RZ_IcetKSemmvmdM-9uhXQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c42a402a57217e090185bc18dfe62b812f7f590f7ddfc6f5f713daff52d971aeddbafde803f7db996c138efa60e13a5beeb8ea25a66e12197525c8a9935e7ded", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c55d3cd5cc3430b55a7cc48b8b95fe20df1d270ddf2b047946aba296b7ebad22c45fa7eba9c2a14a4f5f0e87e3f90772a3cbd78b60582933b54a2bbf50c90605", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e4c650eaacdc9515bbdb09f056dcefd831c8ca3804d941f9c9241ebcba09ff2dd404662265efabb277f14f242bf7037a93dad2aeb95f28e7aff3ac7887bf3a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd48c033599f4c0f6dc334893b1366ade3f2afa6242c96cc05f1091016476fe518ee6325277514136135fec5fdca4613fc34eb779cc2366d6d63c6e633c9b6f8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-02 18:43:00.707503) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:43:00.707791) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1501717282.444678-1993515240 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004492 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-02 18:43:00.789864) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 18:43:00.792198) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-02 18:43:00.792478) ========= ========= Total master_lag: 0.15 =========