builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-040 starttime: 1493714210.66 results: success (0) revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:50.661183) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:50.661952) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:50.662241) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:50.677386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:50.677721) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-02 01:36:51-- 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% 46.1M=0s 2017-05-02 01:36:51 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133322 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:50.825068) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:50.825396) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022063 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:50.874730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-02 01:36:50.875051) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --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=9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-02 01:36:51,467 truncating revision to first 12 chars 2017-05-02 01:36:51,467 Setting DEBUG logging. 2017-05-02 01:36:51,467 attempt 1/10 2017-05-02 01:36:51,467 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-02 01:36:53,229 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.115226 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-02 01:36:53.003883) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:53.004219) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:53.004720) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:53.005053) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-02 01:36:53.016519) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-05-02 01:36:53.016908) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:36:53 INFO - MultiFileLogger online at 20170502 01:36:53 in /builds/slave/test 01:36:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 01:36:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:36:53 INFO - {'append_to_log': False, 01:36:53 INFO - 'base_work_dir': '/builds/slave/test', 01:36:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 01:36:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:36:53 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 01:36:53 INFO - 'buildbot_json_path': 'buildprops.json', 01:36:53 INFO - 'config_files': ('talos/linux_config.py',), 01:36:53 INFO - 'default_actions': ('clobber', 01:36:53 INFO - 'read-buildbot-config', 01:36:53 INFO - 'download-and-extract', 01:36:53 INFO - 'populate-webroot', 01:36:53 INFO - 'create-virtualenv', 01:36:53 INFO - 'install', 01:36:53 INFO - 'run-tests'), 01:36:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:36:53 INFO - 'download_minidump_stackwalk': True, 01:36:53 INFO - 'download_symbols': 'ondemand', 01:36:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:36:53 INFO - 'tooltool.py': '/tools/tooltool.py', 01:36:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:36:53 INFO - '/tools/misc-python/virtualenv.py')}, 01:36:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:36:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:36:53 INFO - 'gecko_profile': False, 01:36:53 INFO - 'gecko_profile_interval': 0, 01:36:53 INFO - 'installer_path': 'installer.exe', 01:36:53 INFO - 'log_level': 'info', 01:36:53 INFO - 'log_name': 'talos', 01:36:53 INFO - 'log_to_console': True, 01:36:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:36:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:36:53 INFO - 'opt_config_files': (), 01:36:53 INFO - 'pip_index': False, 01:36:53 INFO - 'suite': 'dromaeojs', 01:36:53 INFO - 'system_bits': '32', 01:36:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:36:53 INFO - 'title': 'talos-linux64-ix-040', 01:36:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:36:53 INFO - 'use_talos_json': True, 01:36:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:36:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:36:53 INFO - 'work_dir': 'build'} 01:36:53 INFO - [mozharness: 2017-05-02 08:36:53.715063Z] Running clobber step. 01:36:53 INFO - Running pre-action listener: _resource_record_pre_action 01:36:53 INFO - Running main action method: clobber 01:36:53 INFO - rmtree: /builds/slave/test/build 01:36:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:36:55 INFO - Running post-action listener: _resource_record_post_action 01:36:55 INFO - [mozharness: 2017-05-02 08:36:55.170361Z] Finished clobber step (success) 01:36:55 INFO - [mozharness: 2017-05-02 08:36:55.170460Z] Running read-buildbot-config step. 01:36:55 INFO - Running pre-action listener: _resource_record_pre_action 01:36:55 INFO - Running main action method: read_buildbot_config 01:36:55 INFO - Using buildbot properties: 01:36:55 INFO - { 01:36:55 INFO - "project": "", 01:36:55 INFO - "product": "firefox", 01:36:55 INFO - "who": "nobody@mozilla.org", 01:36:55 INFO - "installer_path": "public/build/target.tar.bz2", 01:36:55 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 01:36:55 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora", 01:36:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 01:36:55 INFO - "stage_platform": "linux64", 01:36:55 INFO - "basedir": "/builds/slave/test", 01:36:55 INFO - "buildnumber": 1, 01:36:55 INFO - "platform": "ubuntu64_hw", 01:36:55 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 01:36:55 INFO - "slavebuilddir": "test", 01:36:55 INFO - "taskId": "OraY1nKOT4yb_vV7JGzMHA", 01:36:55 INFO - "branch": "mozilla-aurora", 01:36:55 INFO - "script_repo_revision": "production", 01:36:55 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:36:55 INFO - "slavename": "talos-linux64-ix-040", 01:36:55 INFO - "repo_path": "releases/mozilla-aurora" 01:36:55 INFO - } 01:36:55 INFO - Finding installer, test and symbols from parent task. 01:36:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OraY1nKOT4yb_vV7JGzMHA'}, attempt #1 01:36:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OraY1nKOT4yb_vV7JGzMHA'}, attempt #1 01:36:56 INFO - Task dependencies: RCdtVbohQyGDx_EFPjuKCw 01:36:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw'}, attempt #1 01:36:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.tar.bz2 01:36:56 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/test_packages.json 01:36:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.crashreporter-symbols.zip 01:36:56 INFO - Running post-action listener: _resource_record_post_action 01:36:56 INFO - [mozharness: 2017-05-02 08:36:56.565104Z] Finished read-buildbot-config step (success) 01:36:56 INFO - [mozharness: 2017-05-02 08:36:56.565410Z] Running download-and-extract step. 01:36:56 INFO - Running pre-action listener: _resource_record_pre_action 01:36:56 INFO - Running main action method: download_and_extract 01:36:56 INFO - mkdir: /builds/slave/test/build/tests 01:36:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:36:56 INFO - trying https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.test_packages.json 01:36:56 INFO - Downloading https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:36:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:36:57 INFO - Downloaded 896 bytes. 01:36:57 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:36:57 INFO - Using the following test package requirements: 01:36:57 INFO - {u'common': [u'target.common.tests.zip'], 01:36:57 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:36:57 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:36:57 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:36:57 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:36:57 INFO - u'mozbase': [u'target.common.tests.zip'], 01:36:57 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:36:57 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:36:57 INFO - u'web-platform': [u'target.common.tests.zip', 01:36:57 INFO - u'target.web-platform.tests.zip'], 01:36:57 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:36:57 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:36:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.common.tests.zip 01:36:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:36:57 INFO - Fetch https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.common.tests.zip into memory 01:36:58 INFO - Expected file size: 59542614 01:36:58 INFO - Obtained file size: 59542614 01:37:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:37:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.common.tests.zip 01:37:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:37:08 INFO - Fetch https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.common.tests.zip into memory 01:37:09 INFO - Expected file size: 59542614 01:37:09 INFO - Obtained file size: 59542614 01:37:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.talos.tests.zip 01:37:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:37:19 INFO - Fetch https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.talos.tests.zip into memory 01:37:21 INFO - Expected file size: 14404424 01:37:21 INFO - Obtained file size: 14404424 01:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:21 INFO - trying https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.tar.bz2 01:37:21 INFO - Downloading https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:37:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:37:24 INFO - Downloaded 66749149 bytes. 01:37:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.tar.bz2 01:37:24 INFO - mkdir: /builds/slave/test/properties 01:37:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:37:24 INFO - Writing to file /builds/slave/test/properties/build_url 01:37:24 INFO - Contents: 01:37:24 INFO - build_url:https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.tar.bz2 01:37:24 INFO - Running post-action listener: _resource_record_post_action 01:37:24 INFO - Running post-action listener: set_extra_try_arguments 01:37:24 INFO - [mozharness: 2017-05-02 08:37:24.038606Z] Finished download-and-extract step (success) 01:37:24 INFO - [mozharness: 2017-05-02 08:37:24.038702Z] Running populate-webroot step. 01:37:24 INFO - Running pre-action listener: _resource_record_pre_action 01:37:24 INFO - Running main action method: populate_webroot 01:37:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:37:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:37:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:37:24 INFO - '--tppagecycles', 01:37:24 INFO - '7'], 01:37:24 INFO - 'tests': ['tsvgm']}}, 01:37:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:37:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:37:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:37:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:37:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:37:24 INFO - 'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:37:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:37:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:37:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:37:24 INFO - 'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['damp', 'tps']}, 01:37:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:37:24 INFO - 'tests': ['damp', 'tps']}, 01:37:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['dromaeo_dom']}, 01:37:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:37:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['basic_compositor_video']}, 01:37:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:37:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['a11yr', 01:37:24 INFO - 'ts_paint', 01:37:24 INFO - 'tpaint', 01:37:24 INFO - 'sessionrestore', 01:37:24 INFO - 'sessionrestore_no_auto_restore', 01:37:24 INFO - 'tabpaint']}, 01:37:24 INFO - 'other-e10s': {'tests': ['a11yr', 01:37:24 INFO - 'ts_paint', 01:37:24 INFO - 'tpaint', 01:37:24 INFO - 'sessionrestore', 01:37:24 INFO - 'sessionrestore_no_auto_restore', 01:37:24 INFO - 'tabpaint']}, 01:37:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:37:24 INFO - 'ts_paint', 01:37:24 INFO - 'tpaint', 01:37:24 INFO - 'sessionrestore', 01:37:24 INFO - 'sessionrestore_no_auto_restore', 01:37:24 INFO - 'tabpaint']}, 01:37:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:37:24 INFO - 'ts_paint', 01:37:24 INFO - 'tpaint', 01:37:24 INFO - 'sessionrestore', 01:37:24 INFO - 'sessionrestore_no_auto_restore', 01:37:24 INFO - 'tabpaint']}, 01:37:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['a11yr', 01:37:24 INFO - 'ts_paint', 01:37:24 INFO - 'tpaint', 01:37:24 INFO - 'sessionrestore', 01:37:24 INFO - 'sessionrestore_no_auto_restore', 01:37:24 INFO - 'tabpaint']}, 01:37:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['a11yr', 01:37:24 INFO - 'ts_paint', 01:37:24 INFO - 'tpaint', 01:37:24 INFO - 'sessionrestore', 01:37:24 INFO - 'sessionrestore_no_auto_restore', 01:37:24 INFO - 'tabpaint']}, 01:37:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['tsvgx', 01:37:24 INFO - 'tsvgr_opacity', 01:37:24 INFO - 'tart', 01:37:24 INFO - 'tscrollx', 01:37:24 INFO - 'cart', 01:37:24 INFO - 'tsvg_static']}, 01:37:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:37:24 INFO - 'tsvgr_opacity', 01:37:24 INFO - 'tart', 01:37:24 INFO - 'tscrollx', 01:37:24 INFO - 'cart', 01:37:24 INFO - 'tsvg_static']}, 01:37:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:37:24 INFO - 'talos_options': ['--disable-e10s'], 01:37:24 INFO - 'tests': ['tp5o']}, 01:37:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:37:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:37:24 INFO - 'talos_options': ['--disable-e10s', 01:37:24 INFO - '--xperf_path', 01:37:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:37:24 INFO - 'tests': ['tp5n']}, 01:37:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:37:24 INFO - 'talos_options': ['--xperf_path', 01:37:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:37:24 INFO - 'tests': ['tp5n']}}, 01:37:24 INFO - 'talos.zip': {'path': '', 01:37:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:37:24 INFO - Running post-action listener: _resource_record_post_action 01:37:24 INFO - [mozharness: 2017-05-02 08:37:24.051771Z] Finished populate-webroot step (success) 01:37:24 INFO - [mozharness: 2017-05-02 08:37:24.051861Z] Running create-virtualenv step. 01:37:24 INFO - Running pre-action listener: _resource_record_pre_action 01:37:24 INFO - Running main action method: create_virtualenv 01:37:24 INFO - Creating virtualenv /builds/slave/test/build/venv 01:37:24 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 01:37:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:37:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:37:24 INFO - Using real prefix '/usr' 01:37:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:37:24 INFO - Installing distribute.............................................................................................................................................................................................done. 01:37:26 INFO - Installing pip.................done. 01:37:26 INFO - Return code: 0 01:37:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:37:26 INFO - Reading from file tmpfile_stdout 01:37:26 INFO - Output received: 01:37:26 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) 01:37:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:26 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:37:26 INFO - Using env: {'DISPLAY': ':0', 01:37:26 INFO - 'HOME': '/home/cltbld', 01:37:26 INFO - 'LANG': 'en_US.UTF-8', 01:37:26 INFO - 'LANGUAGE': 'en_US:en', 01:37:26 INFO - 'LOGNAME': 'cltbld', 01:37:26 INFO - 'MAIL': '/var/mail/cltbld', 01:37:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:26 INFO - 'MOZ_NO_REMOTE': '1', 01:37:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:26 INFO - 'NO_EM_RESTART': '1', 01:37:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:26 INFO - 'PWD': '/builds/slave/test', 01:37:26 INFO - 'SHELL': '/bin/bash', 01:37:26 INFO - 'SHLVL': '1', 01:37:26 INFO - 'TERM': 'linux', 01:37:26 INFO - 'TMOUT': '86400', 01:37:26 INFO - 'USER': 'cltbld', 01:37:26 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:26 INFO - '_': '/tools/buildbot/bin/python'} 01:37:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:27 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 01:37:27 INFO - Cleaning up... 01:37:27 INFO - Return code: 0 01:37:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:27 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:37:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:37:27 INFO - Using env: {'DISPLAY': ':0', 01:37:27 INFO - 'HOME': '/home/cltbld', 01:37:27 INFO - 'LANG': 'en_US.UTF-8', 01:37:27 INFO - 'LANGUAGE': 'en_US:en', 01:37:27 INFO - 'LOGNAME': 'cltbld', 01:37:27 INFO - 'MAIL': '/var/mail/cltbld', 01:37:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:27 INFO - 'MOZ_NO_REMOTE': '1', 01:37:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:27 INFO - 'NO_EM_RESTART': '1', 01:37:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:27 INFO - 'PWD': '/builds/slave/test', 01:37:27 INFO - 'SHELL': '/bin/bash', 01:37:27 INFO - 'SHLVL': '1', 01:37:27 INFO - 'TERM': 'linux', 01:37:27 INFO - 'TMOUT': '86400', 01:37:27 INFO - 'USER': 'cltbld', 01:37:27 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:27 INFO - '_': '/tools/buildbot/bin/python'} 01:37:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:27 INFO - Downloading/unpacking psutil>=3.1.1 01:37:27 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 01:37:27 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 01:37:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:27 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 01:37:27 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 01:37:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:37:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:37:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:37:29 INFO - Installing collected packages: psutil 01:37:29 INFO - Running setup.py install for psutil 01:37:29 INFO - building 'psutil._psutil_linux' extension 01:37:29 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 01:37:29 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 01:37:29 INFO - building 'psutil._psutil_posix' extension 01:37:29 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 01:37:29 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 01:37:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:37:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:37:29 INFO - Successfully installed psutil 01:37:29 INFO - Cleaning up... 01:37:29 INFO - Return code: 0 01:37:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:29 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:37:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:37:29 INFO - Using env: {'DISPLAY': ':0', 01:37:29 INFO - 'HOME': '/home/cltbld', 01:37:29 INFO - 'LANG': 'en_US.UTF-8', 01:37:29 INFO - 'LANGUAGE': 'en_US:en', 01:37:29 INFO - 'LOGNAME': 'cltbld', 01:37:29 INFO - 'MAIL': '/var/mail/cltbld', 01:37:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:29 INFO - 'MOZ_NO_REMOTE': '1', 01:37:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:29 INFO - 'NO_EM_RESTART': '1', 01:37:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:29 INFO - 'PWD': '/builds/slave/test', 01:37:29 INFO - 'SHELL': '/bin/bash', 01:37:29 INFO - 'SHLVL': '1', 01:37:29 INFO - 'TERM': 'linux', 01:37:29 INFO - 'TMOUT': '86400', 01:37:29 INFO - 'USER': 'cltbld', 01:37:29 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:29 INFO - '_': '/tools/buildbot/bin/python'} 01:37:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:37:30 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 01:37:30 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 01:37:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:30 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 01:37:30 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 01:37:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:37:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:37:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:37:32 INFO - Installing collected packages: mozsystemmonitor 01:37:32 INFO - Running setup.py install for mozsystemmonitor 01:37:32 INFO - Successfully installed mozsystemmonitor 01:37:32 INFO - Cleaning up... 01:37:32 INFO - Return code: 0 01:37:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:32 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:37:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:37:32 INFO - Using env: {'DISPLAY': ':0', 01:37:32 INFO - 'HOME': '/home/cltbld', 01:37:32 INFO - 'LANG': 'en_US.UTF-8', 01:37:32 INFO - 'LANGUAGE': 'en_US:en', 01:37:32 INFO - 'LOGNAME': 'cltbld', 01:37:32 INFO - 'MAIL': '/var/mail/cltbld', 01:37:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:32 INFO - 'MOZ_NO_REMOTE': '1', 01:37:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:32 INFO - 'NO_EM_RESTART': '1', 01:37:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:32 INFO - 'PWD': '/builds/slave/test', 01:37:32 INFO - 'SHELL': '/bin/bash', 01:37:32 INFO - 'SHLVL': '1', 01:37:32 INFO - 'TERM': 'linux', 01:37:32 INFO - 'TMOUT': '86400', 01:37:32 INFO - 'USER': 'cltbld', 01:37:32 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:32 INFO - '_': '/tools/buildbot/bin/python'} 01:37:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:32 INFO - Downloading/unpacking jsonschema==2.5.1 01:37:32 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 01:37:32 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 01:37:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:32 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 01:37:32 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 01:37:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:37:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:37:34 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 01:37:34 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 01:37:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:34 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 01:37:34 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 01:37:34 INFO - Downloading functools32-3.2.3-2.tar.gz 01:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:37:34 INFO - warning: no files found matching '*.txt' 01:37:34 INFO - no previously-included directories found matching 'build' 01:37:34 INFO - no previously-included directories found matching 'dist' 01:37:34 INFO - no previously-included directories found matching '.git*' 01:37:34 INFO - Installing collected packages: jsonschema, functools32 01:37:34 INFO - Running setup.py install for functools32 01:37:35 INFO - warning: no files found matching '*.txt' 01:37:35 INFO - no previously-included directories found matching 'build' 01:37:35 INFO - no previously-included directories found matching 'dist' 01:37:35 INFO - no previously-included directories found matching '.git*' 01:37:35 INFO - Successfully installed jsonschema functools32 01:37:35 INFO - Cleaning up... 01:37:35 INFO - Return code: 0 01:37:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:35 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:37:35 INFO - Using env: {'DISPLAY': ':0', 01:37:35 INFO - 'HOME': '/home/cltbld', 01:37:35 INFO - 'LANG': 'en_US.UTF-8', 01:37:35 INFO - 'LANGUAGE': 'en_US:en', 01:37:35 INFO - 'LOGNAME': 'cltbld', 01:37:35 INFO - 'MAIL': '/var/mail/cltbld', 01:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:35 INFO - 'MOZ_NO_REMOTE': '1', 01:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:35 INFO - 'NO_EM_RESTART': '1', 01:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:35 INFO - 'PWD': '/builds/slave/test', 01:37:35 INFO - 'SHELL': '/bin/bash', 01:37:35 INFO - 'SHLVL': '1', 01:37:35 INFO - 'TERM': 'linux', 01:37:35 INFO - 'TMOUT': '86400', 01:37:35 INFO - 'USER': 'cltbld', 01:37:35 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:35 INFO - '_': '/tools/buildbot/bin/python'} 01:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:37:35 INFO - Cleaning up... 01:37:35 INFO - Return code: 0 01:37:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:35 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:37:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:37:35 INFO - Using env: {'DISPLAY': ':0', 01:37:35 INFO - 'HOME': '/home/cltbld', 01:37:35 INFO - 'LANG': 'en_US.UTF-8', 01:37:35 INFO - 'LANGUAGE': 'en_US:en', 01:37:35 INFO - 'LOGNAME': 'cltbld', 01:37:35 INFO - 'MAIL': '/var/mail/cltbld', 01:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:35 INFO - 'MOZ_NO_REMOTE': '1', 01:37:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:35 INFO - 'NO_EM_RESTART': '1', 01:37:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:35 INFO - 'PWD': '/builds/slave/test', 01:37:35 INFO - 'SHELL': '/bin/bash', 01:37:35 INFO - 'SHLVL': '1', 01:37:35 INFO - 'TERM': 'linux', 01:37:35 INFO - 'TMOUT': '86400', 01:37:35 INFO - 'USER': 'cltbld', 01:37:35 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:35 INFO - '_': '/tools/buildbot/bin/python'} 01:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:35 INFO - Downloading/unpacking blobuploader==1.2.4 01:37:35 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 01:37:35 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 01:37:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:35 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 01:37:35 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 01:37:37 INFO - Downloading blobuploader-1.2.4.tar.gz 01:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:37:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:37:37 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 01:37:37 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 01:37:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:37 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 01:37:37 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 01:37:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:37:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:37:38 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 01:37:38 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 01:37:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:38 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 01:37:38 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 01:37:38 INFO - Downloading docopt-0.6.1.tar.gz 01:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:37:38 INFO - Installing collected packages: blobuploader, requests, docopt 01:37:38 INFO - Running setup.py install for blobuploader 01:37:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:37:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:37:38 INFO - Running setup.py install for requests 01:37:38 INFO - Running setup.py install for docopt 01:37:38 INFO - Successfully installed blobuploader requests docopt 01:37:38 INFO - Cleaning up... 01:37:38 INFO - Return code: 0 01:37:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:37:38 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:37:38 INFO - Using env: {'DISPLAY': ':0', 01:37:39 INFO - 'HOME': '/home/cltbld', 01:37:39 INFO - 'LANG': 'en_US.UTF-8', 01:37:39 INFO - 'LANGUAGE': 'en_US:en', 01:37:39 INFO - 'LOGNAME': 'cltbld', 01:37:39 INFO - 'MAIL': '/var/mail/cltbld', 01:37:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:39 INFO - 'MOZ_NO_REMOTE': '1', 01:37:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:39 INFO - 'NO_EM_RESTART': '1', 01:37:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:39 INFO - 'PWD': '/builds/slave/test', 01:37:39 INFO - 'SHELL': '/bin/bash', 01:37:39 INFO - 'SHLVL': '1', 01:37:39 INFO - 'TERM': 'linux', 01:37:39 INFO - 'TMOUT': '86400', 01:37:39 INFO - 'USER': 'cltbld', 01:37:39 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:39 INFO - '_': '/tools/buildbot/bin/python'} 01:37:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:37:39 INFO - Running setup.py (path:/tmp/pip-zYfNhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:37:39 INFO - Running setup.py (path:/tmp/pip-8FvDEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:37:39 INFO - Running setup.py (path:/tmp/pip-4WifH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:37:39 INFO - Running setup.py (path:/tmp/pip-W1CeT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:37:39 INFO - Running setup.py (path:/tmp/pip-2NTZgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:37:39 INFO - Running setup.py (path:/tmp/pip-7TI1Iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:37:39 INFO - Running setup.py (path:/tmp/pip-Xxr_vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:37:39 INFO - Running setup.py (path:/tmp/pip-n8JOLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:37:39 INFO - Running setup.py (path:/tmp/pip-f9xoXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:37:39 INFO - Running setup.py (path:/tmp/pip-Lc11aA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:37:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:37:39 INFO - Running setup.py (path:/tmp/pip-eVdR66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:37:40 INFO - Running setup.py (path:/tmp/pip-1D4w2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:37:40 INFO - Running setup.py (path:/tmp/pip-Cqw0A_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:37:40 INFO - Running setup.py (path:/tmp/pip-_NnfNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:40 INFO - Running setup.py (path:/tmp/pip-Zay4o7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:37:40 INFO - Running setup.py (path:/tmp/pip-EOgUbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:37:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:37:40 INFO - Running setup.py (path:/tmp/pip-lB0qKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:37:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:37:40 INFO - Running setup.py install for manifestparser 01:37:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:37:40 INFO - Running setup.py install for mozcrash 01:37:40 INFO - Running setup.py install for mozdebug 01:37:40 INFO - Running setup.py install for mozdevice 01:37:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:37:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:37:40 INFO - Running setup.py install for mozfile 01:37:40 INFO - Running setup.py install for mozhttpd 01:37:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Running setup.py install for mozinfo 01:37:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Running setup.py install for mozInstall 01:37:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Running setup.py install for mozleak 01:37:41 INFO - Running setup.py install for mozlog 01:37:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Running setup.py install for moznetwork 01:37:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Running setup.py install for mozprocess 01:37:41 INFO - Running setup.py install for mozprofile 01:37:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Running setup.py install for mozrunner 01:37:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:37:41 INFO - Running setup.py install for mozscreenshot 01:37:42 INFO - Running setup.py install for moztest 01:37:42 INFO - Running setup.py install for mozversion 01:37:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:37:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:37:42 INFO - Cleaning up... 01:37:42 INFO - Return code: 0 01:37:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:42 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:42 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:37:42 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:37:42 INFO - Using env: {'DISPLAY': ':0', 01:37:42 INFO - 'HOME': '/home/cltbld', 01:37:42 INFO - 'LANG': 'en_US.UTF-8', 01:37:42 INFO - 'LANGUAGE': 'en_US:en', 01:37:42 INFO - 'LOGNAME': 'cltbld', 01:37:42 INFO - 'MAIL': '/var/mail/cltbld', 01:37:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:42 INFO - 'MOZ_NO_REMOTE': '1', 01:37:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:42 INFO - 'NO_EM_RESTART': '1', 01:37:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:42 INFO - 'PWD': '/builds/slave/test', 01:37:42 INFO - 'SHELL': '/bin/bash', 01:37:42 INFO - 'SHLVL': '1', 01:37:42 INFO - 'TERM': 'linux', 01:37:42 INFO - 'TMOUT': '86400', 01:37:42 INFO - 'USER': 'cltbld', 01:37:42 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:42 INFO - '_': '/tools/buildbot/bin/python'} 01:37:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:37:42 INFO - Running setup.py (path:/tmp/pip-4TGm8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:37:42 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)) 01:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:37:42 INFO - Running setup.py (path:/tmp/pip-NWqfI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:37:42 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)) 01:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:37:42 INFO - Running setup.py (path:/tmp/pip-T3by5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:37:42 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)) 01:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:37:42 INFO - Running setup.py (path:/tmp/pip-gTl6qO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 01:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:37:42 INFO - Running setup.py (path:/tmp/pip-2ZH1zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:37:42 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)) 01:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:37:42 INFO - Running setup.py (path:/tmp/pip-7SkogC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:37:42 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)) 01:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:37:42 INFO - Running setup.py (path:/tmp/pip-uBbO9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:37:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 01:37:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:37:42 INFO - Running setup.py (path:/tmp/pip-eQykUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 01:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:37:43 INFO - Running setup.py (path:/tmp/pip-y_njCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:37:43 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)) 01:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:37:43 INFO - Running setup.py (path:/tmp/pip-UewVd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:37:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 01:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:37:43 INFO - Running setup.py (path:/tmp/pip-a_pQIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:37:43 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)) 01:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:37:43 INFO - Running setup.py (path:/tmp/pip-IcERCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:37:43 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)) 01:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:37:43 INFO - Running setup.py (path:/tmp/pip-wp1w9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:37:43 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)) 01:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:37:43 INFO - Running setup.py (path:/tmp/pip-cSKE1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:37:43 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)) 01:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:43 INFO - Running setup.py (path:/tmp/pip-yme50r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:37:43 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)) 01:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:37:43 INFO - Running setup.py (path:/tmp/pip-T75Qz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:37:43 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)) 01:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:37:43 INFO - Running setup.py (path:/tmp/pip-8lqog1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:37:43 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)) 01:37:43 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)) 01:37:43 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)) 01:37:43 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)) 01:37:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:37:43 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:37:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:37: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 01:37: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 01:37:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:37: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 01:37: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 01:37:45 INFO - Downloading blessings-1.6.tar.gz 01:37:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:37:45 INFO - Installing collected packages: blessings 01:37:45 INFO - Running setup.py install for blessings 01:37:45 INFO - Successfully installed blessings 01:37:45 INFO - Cleaning up... 01:37:45 INFO - Return code: 0 01:37:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:37:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:37:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:37:46 INFO - Reading from file tmpfile_stdout 01:37:46 INFO - Current package versions: 01:37:46 INFO - argparse == 1.2.1 01:37:46 INFO - blessings == 1.6 01:37:46 INFO - blobuploader == 1.2.4 01:37:46 INFO - docopt == 0.6.1 01:37:46 INFO - functools32 == 3.2.3-2 01:37:46 INFO - jsonschema == 2.5.1 01:37:46 INFO - manifestparser == 1.1 01:37:46 INFO - mozInstall == 1.12 01:37:46 INFO - mozcrash == 1.0 01:37:46 INFO - mozdebug == 0.1 01:37:46 INFO - mozdevice == 0.49 01:37:46 INFO - mozfile == 1.2 01:37:46 INFO - mozhttpd == 0.7 01:37:46 INFO - mozinfo == 0.9 01:37:46 INFO - mozleak == 0.1 01:37:46 INFO - mozlog == 3.4 01:37:46 INFO - moznetwork == 0.27 01:37:46 INFO - mozprocess == 0.25 01:37:46 INFO - mozprofile == 0.28 01:37:46 INFO - mozrunner == 6.13 01:37:46 INFO - mozscreenshot == 0.1 01:37:46 INFO - mozsystemmonitor == 0.3 01:37:46 INFO - moztest == 0.8 01:37:46 INFO - mozversion == 1.4 01:37:46 INFO - psutil == 3.1.1 01:37:46 INFO - requests == 1.2.3 01:37:46 INFO - wsgiref == 0.1.2 01:37:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:46 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:46 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 01:37:46 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:37:46 INFO - Using env: {'DISPLAY': ':0', 01:37:46 INFO - 'HOME': '/home/cltbld', 01:37:46 INFO - 'LANG': 'en_US.UTF-8', 01:37:46 INFO - 'LANGUAGE': 'en_US:en', 01:37:46 INFO - 'LOGNAME': 'cltbld', 01:37:46 INFO - 'MAIL': '/var/mail/cltbld', 01:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:46 INFO - 'MOZ_NO_REMOTE': '1', 01:37:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:46 INFO - 'NO_EM_RESTART': '1', 01:37:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:46 INFO - 'PWD': '/builds/slave/test', 01:37:46 INFO - 'SHELL': '/bin/bash', 01:37:46 INFO - 'SHLVL': '1', 01:37:46 INFO - 'TERM': 'linux', 01:37:46 INFO - 'TMOUT': '86400', 01:37:46 INFO - 'USER': 'cltbld', 01:37:46 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:46 INFO - '_': '/tools/buildbot/bin/python'} 01:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:46 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)) 01:37:46 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)) 01:37:46 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)) 01:37:46 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)) 01:37:46 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)) 01:37:46 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)) 01:37:46 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)) 01:37:46 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)) 01:37:46 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)) 01:37:46 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)) 01:37:46 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)) 01:37:46 INFO - Cleaning up... 01:37:46 INFO - Return code: 0 01:37:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:37:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:37:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:37:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:37: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x26431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2681a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28e1310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, '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': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', '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 01:37:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:37:46 INFO - Using env: {'DISPLAY': ':0', 01:37:46 INFO - 'HOME': '/home/cltbld', 01:37:46 INFO - 'LANG': 'en_US.UTF-8', 01:37:46 INFO - 'LANGUAGE': 'en_US:en', 01:37:46 INFO - 'LOGNAME': 'cltbld', 01:37:46 INFO - 'MAIL': '/var/mail/cltbld', 01:37:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:46 INFO - 'MOZ_NO_REMOTE': '1', 01:37:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:46 INFO - 'NO_EM_RESTART': '1', 01:37:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:46 INFO - 'PWD': '/builds/slave/test', 01:37:46 INFO - 'SHELL': '/bin/bash', 01:37:46 INFO - 'SHLVL': '1', 01:37:46 INFO - 'TERM': 'linux', 01:37:46 INFO - 'TMOUT': '86400', 01:37:46 INFO - 'USER': 'cltbld', 01:37:46 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:37:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:46 INFO - '_': '/tools/buildbot/bin/python'} 01:37:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:37:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:37:46 INFO - Cleaning up... 01:37:46 INFO - Return code: 0 01:37:46 INFO - Running post-action listener: _resource_record_post_action 01:37:46 INFO - Running post-action listener: _start_resource_monitoring 01:37:46 INFO - Starting resource monitoring. 01:37:46 INFO - [mozharness: 2017-05-02 08:37:46.686512Z] Finished create-virtualenv step (success) 01:37:46 INFO - [mozharness: 2017-05-02 08:37:46.686984Z] Running install step. 01:37:46 INFO - Running pre-action listener: _resource_record_pre_action 01:37:46 INFO - Running main action method: install 01:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:37:46 INFO - Reading from file tmpfile_stdout 01:37:46 INFO - Detecting whether we're running mozinstall >=1.0... 01:37:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:37:46 INFO - Reading from file tmpfile_stdout 01:37:46 INFO - Output received: 01:37:46 INFO - Usage: mozinstall [options] installer 01:37:46 INFO - Options: 01:37:46 INFO - -h, --help show this help message and exit 01:37:46 INFO - -d DEST, --destination=DEST 01:37:46 INFO - Directory to install application into. [default: 01:37:46 INFO - "/builds/slave/test"] 01:37:46 INFO - --app=APP Application being installed. [default: firefox] 01:37:46 INFO - mkdir: /builds/slave/test/build/application 01:37:46 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'] 01:37:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:38:03 INFO - Reading from file tmpfile_stdout 01:38:03 INFO - Output received: 01:38:03 INFO - /builds/slave/test/build/application/firefox/firefox 01:38:03 INFO - Running post-action listener: _resource_record_post_action 01:38:03 INFO - [mozharness: 2017-05-02 08:38:03.563349Z] Finished install step (success) 01:38:03 INFO - [mozharness: 2017-05-02 08:38:03.563625Z] Running run-tests step. 01:38:03 INFO - Running pre-action listener: _resource_record_pre_action 01:38:03 INFO - Running main action method: run_tests 01:38:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:38:03 INFO - Python 2.7.3 01:38:03 INFO - Return code: 0 01:38:03 INFO - grabbing minidump binary from tooltool 01:38:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 01:38:03 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 0x28de400>, '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 0x28df3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28df840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:38:03 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 01:38:03 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 01:38:03 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 01:38:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:38:03 INFO - Return code: 0 01:38:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:38:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:38:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:38:03 INFO - ENV: RUST_BACKTRACE is now 1 01:38:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:38:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:38:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:38:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-040 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:38:03 INFO - Using env: {'DISPLAY': ':0', 01:38:03 INFO - 'HOME': '/home/cltbld', 01:38:03 INFO - 'LANG': 'en_US.UTF-8', 01:38:03 INFO - 'LANGUAGE': 'en_US:en', 01:38:03 INFO - 'LOGNAME': 'cltbld', 01:38:03 INFO - 'MAIL': '/var/mail/cltbld', 01:38:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:38:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:38:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:03 INFO - 'MOZ_NO_REMOTE': '1', 01:38:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:38:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:38:03 INFO - 'NO_EM_RESTART': '1', 01:38:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:38:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:03 INFO - 'PWD': '/builds/slave/test', 01:38:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:38:03 INFO - 'RUST_BACKTRACE': '1', 01:38:03 INFO - 'SHELL': '/bin/bash', 01:38:03 INFO - 'SHLVL': '1', 01:38:03 INFO - 'TERM': 'linux', 01:38:03 INFO - 'TMOUT': '86400', 01:38:03 INFO - 'USER': 'cltbld', 01:38:03 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102', 01:38:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:03 INFO - '_': '/tools/buildbot/bin/python'} 01:38:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:38:03 INFO - mozversion application_buildid: 20170502074838 01:38:03 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:38:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:38:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:38:03 INFO - mozversion application_name: Firefox 01:38:03 INFO - mozversion application_remotingname: firefox-dev 01:38:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:38:03 INFO - mozversion application_vendor: Mozilla 01:38:03 INFO - mozversion application_version: 54.0a2 01:38:03 INFO - mozversion platform_buildid: 20170502074838 01:38:03 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:38:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:38:03 INFO - mozversion platform_version: 54.0a2 01:38:03 INFO - using testdate: 1493714283 01:38:03 INFO - actual date: 1493714283 01:38:03 INFO - starting webserver on 'localhost:51628' 01:38:03 INFO - SUITE-START | Running 2 tests 01:38:03 INFO - TEST-START | dromaeo_css 01:38:03 INFO - operating with platform_type : linux_ 01:38:03 INFO - Initialising browser for dromaeo_css test... 01:38:03 INFO - TEST-INFO | started process 1890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpISp2qn/profile http://localhost:51628/getInfo.html) 01:38:07 INFO - PROCESS | 1890 | __metrics Screen width/height:1600/1200 01:38:07 INFO - PROCESS | 1890 | colorDepth:24 01:38:07 INFO - PROCESS | 1890 | Browser inner width/height: 1024/697 01:38:07 INFO - PROCESS | 1890 | __metrics 01:38:08 INFO - PROCESS | 1890 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:38:08 INFO - PROCESS | 1890 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:38:09 INFO - TEST-INFO | 1890: exit 0 01:38:09 INFO - Browser initialized. 01:38:09 INFO - Running cycle 1/1 for dromaeo_css test... 01:38:09 INFO - TEST-INFO | started process 1980 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpISp2qn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:38:21 INFO - PROCESS | 1980 | RSS: Main: 139882496 01:38:21 INFO - PROCESS | 1980 | 01:41:23 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:51628/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51628/tests/dromaeo/cssquery-ext.html) 01:41:24 INFO - PROCESS | 1980 | RSS: Main: 232640512 01:41:24 INFO - PROCESS | 1980 | 01:43:12 INFO - PROCESS | 1980 | ************************* 01:43:12 INFO - PROCESS | 1980 | A coding exception was thrown and uncaught in a Task. 01:43:12 INFO - PROCESS | 1980 | 01:43:12 ERROR - PROCESS | 1980 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:43:12 INFO - PROCESS | 1980 | Full stack: 01:43:12 INFO - PROCESS | 1980 | ************************* 01:43:12 INFO - PROCESS | 1980 | ************************* 01:43:12 INFO - PROCESS | 1980 | A coding exception was thrown and uncaught in a Task. 01:43:12 INFO - PROCESS | 1980 | 01:43:12 ERROR - PROCESS | 1980 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:43:12 INFO - PROCESS | 1980 | Full stack: 01:43:12 INFO - PROCESS | 1980 | ************************* 01:43:12 INFO - PROCESS | 1980 | ************************* 01:43:12 INFO - PROCESS | 1980 | A coding exception was thrown and uncaught in a Task. 01:43:12 INFO - PROCESS | 1980 | 01:43:12 ERROR - PROCESS | 1980 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:43:12 INFO - PROCESS | 1980 | Full stack: 01:43:12 INFO - PROCESS | 1980 | ************************* 01:43:12 INFO - PROCESS | 1980 | ************************* 01:43:12 INFO - PROCESS | 1980 | A coding exception was thrown and uncaught in a Task. 01:43:12 INFO - PROCESS | 1980 | 01:43:12 ERROR - PROCESS | 1980 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:43:12 INFO - PROCESS | 1980 | Full stack: 01:43:12 INFO - PROCESS | 1980 | ************************* 01:44:26 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:51628/tests/dromaeo/cssquery-ext.html (next: http://localhost:51628/tests/dromaeo/cssquery-jquery.html) 01:44:27 INFO - PROCESS | 1980 | RSS: Main: 217817088 01:44:27 INFO - PROCESS | 1980 | 01:47:29 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:51628/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51628/tests/dromaeo/cssquery-mootools.html) 01:47:29 INFO - PROCESS | 1980 | RSS: Main: 212979712 01:47:29 INFO - PROCESS | 1980 | 01:50:31 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:51628/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51628/tests/dromaeo/cssquery-prototype.html) 01:50:32 INFO - PROCESS | 1980 | RSS: Main: 176070656 01:50:32 INFO - PROCESS | 1980 | 01:53:34 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:51628/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51628/tests/dromaeo/cssquery-yui.html) 01:53:35 INFO - PROCESS | 1980 | RSS: Main: 178151424 01:53:35 INFO - PROCESS | 1980 | 01:56:38 INFO - PROCESS | 1980 | Cycle 1(1): loaded http://localhost:51628/tests/dromaeo/cssquery-yui.html (next: http://localhost:51628/tests/dromaeo/cssquery-dojo.html) 01:56:38 INFO - PROCESS | 1980 | RSS: Main: 267751424 01:56:38 INFO - PROCESS | 1980 | 01:56:38 INFO - PROCESS | 1980 | __start_tp_report 01:56:38 INFO - PROCESS | 1980 | _x_x_mozilla_page_load 01:56:38 INFO - PROCESS | 1980 | _x_x_mozilla_page_load_details 01:56:38 INFO - PROCESS | 1980 | |i|pagename|runs| 01:56:38 INFO - PROCESS | 1980 | |0;dojo.html;3426.9143271418197;3435.4159792835217;3466.8959931202066;3476.130967258184;3521.084518025314;5731.856703582413;5745.569082318827;5768.596198266123;5777.508911742507;5811.389804070575;5777.682227477487;5830.591858569902;5838.824835254949;5846.6199697019665;5846.795362162326;5162.070827251112;5167.199084141964;5179.974100129513;5239;5250.7637156328055;5036.395632524084;5049.596032317406;5258.55302299305;5267.841964741064;5268.525832675058;5126.692398456086;5144.8971020579565;5220.738963051832;5239.266502689627;5240.764165612538;5248.685078895279;5249.081410753122;5253.921191182136;5254.8160814371695;5280.313559237313;4965.90068198634;5117.641765076446;5181.792728290864;5228.895422091537;5236.371635403738;5602.439756024406;5641.069223578104;5687.289088863892;5700.572457065737;5703.7148142592705;6123.479504242145;6175.876482470326;6176.227971503562;6176.598521096114;6185.381461853824;6183.721732522047;6212;6215.689215539205;6220.937790622081;6236.844078898064;5584.664920104807;5664.688442135681;5721.284839395117;5810.244668193108;5812.418758124239;6356.586821856657;6357.523185760994;6366.681665916685;6366.904496432558;6375.2987171411105;6091.360407157274;6125.877482450326;6135.938640613536;6145;6151.815445536641;5967;5998.100284957204;6124.594542075475;6127.234095738033;6201.782937597162;6137.8060289513105;6149.66176860268;6168.784092556829;6174.38256174388;6197.96901015492;5954.285485741739;6011.549133814894;6086.087086936995;6119.418655227746;6197.628142311386;4554.017283138437;4619.745913974763;4644.303354496785;4645.976770116195;4668.089722504079;4093.3655283431117;4110.0135967368215;4136.710430269913;4137.668986481074;4230.746155230696;5931.110333450017;5944.7919322824355;5952.315483719428;5974.103884417372;5990.101484777231;6190.7523699051535;6233.937660623426;6234.781782637586;6236.968815155986;6240.500759939194;5782.942170578241;5835.008048631743;5920.733566989496;5944.940550594439;5960.612560183574;5301.920471192935;5308.177232528964;5334.946650533445;5335.519803217729;5341.118715411992;6555.311692272338;6559.6064236145985;6560.212774467094;6569.83575410609;6571.967140164268;5956.047032474957;6060.393960603904;6250.842246589816;6284.402981716729;6285.2457705075685;6341.239051313872;6344.048392741126;6438.6458744767615;6483.643399613066;6494.480441564758;6086.178365920544;6100.908486372797;6104.755809767737;6105.84735381619;6109.236345456818;5989.460948514655;6211.223597050369;6217.906731398942;6221.844453888508;6221.844453888689;5988.910166347421;6023.548233882389;6025;6132.264128304632;6134.969325153345;6020.54845886569;6074.726637301421;6083.117947897618;6084.69576521181;6089.421504957022;5883.441073098049;5952.880942381042;6053.545984051126;6065.4541091302;6076.088586712029;5930.11048342752;6013.30846952606;6086.300075491198;6119.746288422617;6137.7851775187655;5956.374580668968;5967.880642387041;5969.253843269591;5972.462478376967;5972.701364931823 01:56:38 INFO - PROCESS | 1980 | |1;ext.html;772.7990722412175;818.9058258300296;835.8620827563452;900.3607438718509;900.7522931193924;31433.685663143362;43993.78003109984;44761;44801.551984480146;44926.32610510841;43354.56645433545;43370.132597348034;43501.564984350145;43591.78204108983;43604.127917441714;7673;7720.6911723531275;9000.414973026765;9124.908750912473;9128.634854605807;10524.631637892675;10576.471176441186;11083.72290692731;11099.500522476508;11109.277896936674;10864.45677716115;10891.292066015683;10901.291416057971;10905.291156074869;10913.23607347486;10804.621838235664;10825.188110891715;10828.783424331508;10840.078593319498;10848.132149428027;10443.477826108665;10496.422696751675;10501;10503.632372866912;10509.421981790998;42185.578144218474;42414.78792606017;42554.1489170218;42624.57375426268;42777.57222427798;4191.7275377100395;4239.47006624172;4273.465816772903;4355.303151495746;4388.495323037828;29368.70631293681;29773.851130744424;30066.24834379136;30415;31382.686173138434;4281.143771245764;4368.104538569585;4390.8902277443;4399.490029681884;4429.424174857248;231434;231702;231780;232190;232319;52768;52836.47163528315;52855.735721321915;52900.47099528956;52973.205401919;55336.44663553313;55386.16920746192;55550.61123471946;55660;55689;16685;16886.15569221534;16890.662186756195;16897.83102168987;16917.661646766996;12694.49222031127;12884.677883052998;15004.249787510798;15013.69972600542;15021.549353519413;13127.474901004043;13280.402381892838;13348.532801351907;13449.125806822722;13739.381727822272;5598.888022239533;5604.85987850307;5626.831195064155;5626.859328516738;5800.4489573490455;993.9950300248453;998.1765043838863;1009.6264382178641;1021.2595867995643;1041.5156952017348;28302.150935471556;28746.712532874404;28775.712242877304;28855.711442885302;29015;1076.1444651502075;1084.2356138922041;1095.8246680531236;1098.0337303173158;1115.2973626615342;14391.78412323816;15290.923545382424;15330.310136043905;15370.154641494824;15434.768478472832;291050;331092;335676;446729;448750;50776;50828.49171508312;51100.48899511032;51186.232206516936;51211.74394128005;1421.3562773241763;1460.832004319517;1468.258529442646;1480.6668499587722;1486.5614643679924;26293;28634.713652863204;28704;28876.133715988555;28942.276443089093;37823.243535129695;37852.62147378491;37896;37925;37969.810150949066;298858;299550;300753;301135;301615;29226.269343266587;29294.853525732236;29327.560086599144;30205.395892082473;30358.392832142792;40371.79814100911;40826.1834763309;40853.38719919263;40911.1817763649;41254.79372603118;31538.211544711565;31670.208244794063;31691.207719806265;32051.67948320487;32242.83878580592;31998.68001319957;32005.839970799996;32157.678423215468;32197;32412.675873240965;36883.815580921924;36931.44602830906;37499;37824.43263351107;37879.2424151521;30749.23126921845;30903;30936;31183;31227.219319517193;24628.507429850943;25057.49885002326;25243.747562524874;25274;25468 01:56:38 INFO - PROCESS | 1980 | |2;jquery.html;436.1908659436743;470.88698712309036;471.67218782945855;484.79880849447505;491.6018025399427;18936.337228197015;20210.393688189342;20252.898735506322;20312.99999999998;20428.182872685113;20372.898135509302;20526.384208473733;20624.69062964057;20681.172753089857;20916.790832091676;4996;6479.93520064798;6733.192016957972;6742.089817874597;6750.898736518956;6518.771842985497;6553.082568440422;6581.440577550914;6597.406233438989;6603.141591593087;6373.553851230416;6591.967040164793;6598.37315455031;6621.73513059477;6635.43598794103;6455.451286640641;6526.021096835465;6610.438112760421;6610.438112760421;6674.56615320005;6427.068075129127;6464.095026696266;6470.579412338183;6480.4491618212505;6481.28705842357;23157.305280841603;23622.527549448914;23708.288751337488;23832.88083559571;24030;3461.152017755641;3515.789052656849;3537.6285490023433;3546.982265088684;3627.3470775260444;15257.703095236919;15948.920255398765;15951.760723589156;16217;16454.424095156613;2177.0203408466255;2181.3128864407663;2203.3500117465387;2238.507528343762;2238.947694583543;77385;95618.5219073907;96532;97546;100150.49924750402;14428.207824369392;17627.82699114172;18514.056024117544;18899.433017009513;18968.336108236144;27327.316817079336;28531;35607.82196089003;35660.28679426397;35713.28573428517;10075.09324160829;10082.19342452602;10181.694549163536;10255.7436064099;10301.690949271533;8667.566621668891;8667.696630617898;8707.47755134694;8943.821123577492;8951.641934322684;8860.911390886138;8902.955485222661;9024.8646270306;9097.954510227539;9187.77030574241;3775.8867233983024;3869.28418242626;3881.7476864003747;3922.5489068756888;3925.685945124383;931.9161275485238;944.1927152284835;946.6450081219542;957.9281553883486;965.9082387173207;19747.60504789896;19870.50323741918;20712.171513139307;21324.12124363222;21423.785762142492;1071.2019545438698;1089.6567581211893;1092.6121226964312;1131.660501849442;1137.7724455109012;10455.68632941013;11069.169812263952;11179.385133817717;11230.494627741771;11309.208355415041;663540;677077;678753;689181;707419;89216;91482.54258728797;113755.43122284336;123476;125947.3702631481;1322.3322222277689;1328.8307386882354;1346.5556366399064;1362.768329384007;1366.9043166978415;13826;17817.554561136076;18018.2792688291;19245.326413575458;19344.806551934864;52180;53150;53706.73146634242;54771.452285478226;54805.45194548163;65689.67155164384;66120;66656.6667166661;67541;68999.89500472591;11888.643340699793;11964.46159922823;11990.280583165038;12079.637610871689;12132.636020919386;12792.36038198105;12804.743905122032;13008.47966081384;13027.609171724864;13218.867811322149;11495.080393568327;11602.129840262183;11610.24533405326;11630.418479076181;11868.525258989544;8442.66229350819;8665.133486651284;10309.48452577353;10322.329048611817;10500.579976800842;10641.414722190353;11160.218784684994;11309.434528273718;11369.829452558384;11384.43077846121;12048.397580121135;12069.155159139127;12091.637250882488;12147.939260303641;12531.62152163243;10958.616448424267;11110.388928608681;11241.437928103725;11441.313521188755;11518.884811151782 01:56:38 INFO - PROCESS | 1980 | |3;mootools.html;1516.109912988041;1549.4576898085663;1576.0543673795723;1582.1140161509556;1582.6676397956426;10954;11692.415379231026;11692.649220523377;11697.415129243536;11704.473298701558;11188.440577971109;11445.427728613578;11452.198346115776;11465.713357166074;11519.884801152008;2414.7464516225764;2448.52253810507;2452.9386765330833;2455.1407007547305;2455.4475243070256;2358.7523310052457;2419.5523828091773;2421.32202983165;2422.34596658902;2446.107170882633;2391.1272385579314;2432.62294344376;2445.596476581362;2470.703515578133;2473.183849329726;2341.4263505441277;2396.2451827674226;2405.518896220754;2408.5303365843665;2417.6857008588945;2369.7037870266217;2383.3564937466786;2391.1152873436768;2392.306231192952;2396.6524853896262;10987.066099381562;11028.338299702044;11085.168612354106;11089.667309980714;11093.16801239902;5904.498117660004;6055.152278680988;6060.727267272983;6077.817665469999;6078.969605151946;3397.252604427022;3422.9657703422895;3497.2306092659574;3616.1140520572367;3617.04148400674;1360.2994457854213;1390.9468716168014;1407.3596513586294;1414.3847426369562;1428.9857101428956;16615.584610384718;16865.156742162966;16942.407015754514;17039.318427262893;17044.57388565271;4922.827701030446;4997.250412438163;5057.039162559102;5070.340855688737;5121.4622464641425;13113.934430327978;13141.605751827461;13154.079214455087;13159.473621055242;13160.934195329153;6137.631742095488;6146.323904370515;6147.631142131487;6152.2002139722445;6156.415140561639;5498.147787093007;5608.327000759935;5703.17303990919;5757.309122905278;5760.0207964646115;3821.6178382162116;3937.3109705801944;3954.6440820325734;3986.8405263789705;4017.7187596868525;1030.701096681969;1033.3283365812283;1044.0290529807353;1044.9007344302279;1045.153425725165;863.5509535041743;875.3085062800444;900.6127365233012;930.0745757970863;1106.9612563560397;7145.4640901931525;7214.819629509199;7216;7278.163011253668;7390.29792169743;1070.844727514506;1094.2941802537318;1101.9063379612662;1122.2705241592867;1160.0545555372344;4500.684952053325;4647.419072615923;4816.349792778;4842.95157048425;4921.803127874917;14799.112053276838;14995.100293982396;15050.924745376204;15405.768913466307;15408.537743867477;15100.773488397683;15116.697666046617;15216.467423640119;15243.23783810805;15285.847141528442;10883.455827208609;10903.34579925207;10949.124070074216;11109.00018998294;11117.888821111846;7066.434685225273;7133.500654954103;7268.527545709512;7286.854262914606;7399.075115610549;13101.803472948104;13105.475780968654;13255;13514.932425338204;13541.932290338485;13514.662133446742;13535.187888726314;13678.452861885413;13681.179129252278;13821.654458638212;9135.497547634945;9409.905900941178;9802.019798020141;9914.801703966024;10003.649872254728;11051.778964420828;11165.776684466102;11221.88778112241;11351.14866385041;11397.829032564685;9108.225800807006;9189.816203676022;9351.438913665203;9582.568784404857;9616.615335386787;9149.222316103205;9152.542372881197;9200.263978881807;9326.393784403992;9530.618775248913;8652;8656.220940115169;8729.869051964353;8738.388312818042;8747.781305467413;6324.462420694293;6355.618662880242;6448.806535803933;6492.415682588589;6521.9673901630185;6530.47756179514;6600.702968366339;6674.466042716668;6681.799546013627;6684.732610695518 01:56:38 INFO - PROCESS | 1980 | |4;prototype.html;417.351019165198;418.18036648169584;422.9598188172124;425.6148185891767;427.7606728774863;5201.739913004349;5334.839954801352;5342.946570534294;5346.2782524359245;5379.596530260226;5049.596032317415;5157.21510384392;5167.638265321429;5173.974130129344;5174.172132458798;4125.876223713293;4135.73117747347;4151.626353628173;4156.97921510392;4186.204621121992;4185.714214341727;4194.804480722994;4196.181744559812;4201;4213.452251207339;3964.2864284428792;4054.493188351456;4144.896377590554;4154.729942553739;4181.540030596632;4085.346344584852;4105.609967053125;4121.5476791309275;4137.81379837908;4147.8963025924295;3733.7386382953205;3736.4582135590185;3769.963260103466;3774.358359078963;3797.582265950743;4847.951520484785;5073.39119305682;5133.537981581639;5203.765830537597;5224.63427560071;4988.251762235694;4990.426100998396;5008.524190201925;5042.546170844642;5075;5508.338999320067;5553.555715542747;5556.249906262643;5572.832815015556;5625.17181915879;4450.577195166454;4498.932516012263;4583.381243532114;4590.104929538741;4610.377599024156;5411.810586629488;5534.169874518814;5612.635178713412;5695.288088988877;5720.542356611461;4628.745419001987;4662.207424737802;4696.7416792076765;4708.88227794301;4715.669903106749;4567.898766567416;4766.475687674353;4780.808767649255;4785.688930219594;4799.208130658402;4941.777620007108;4945.406551213805;5029.497050295015;5062.443131255559;5063.670861393997;5442.863928401743;5479.232907392889;5490.203920431515;5500.167413781905;5517.862053448696;1573.3785154863995;1618.6034421566792;1649.2743192995044;1649.364994477135;1661.5929097371222;1272.5048068519452;1279.929603871796;1284.800855867342;1285.190330092036;1291.3156027305542;5386.444946335399;5389.649672771257;5400.91898621521;5406.216098665672;5421.918671219935;5673.34069525154;5681.545476361881;5683.744231509592;5688.800891968761;5697.4302569742695;5097.796088156432;5380.973095134499;5385.5960802939935;5401.864953376197;5425.209680805325;2938.2654336415894;2980.389020250887;2985.7163569460868;2986.25343664084;2989.5515672649276;5723.710515793633;5875.735591898375;5927.4665280125;5931.58478906481;5949.8215053548465;5725.5133313667975;5762.279715035621;5770.711464426762;5772.971135144381;5786.2477877876445;5857.326407463111;5914.142449344821;5919.260092488439;5953.851153721192;5974.492168165581;5346.9465305348;5614.522765564972;5639.351474580311;5699.800506982236;5705.429457054262;5335.546478549367;5443.972780136074;5454.809081682122;5606.607537472501;5612.298462692163;5927.585069045125;5947.851303717269;5961.880762384815;5968.731407086605;5985.521158307412;4764.49972752856;4775.6179505638775;4777.235642297216;4777.474477807369;4796.856094317176;5377.758000889891;5630.88738225225;5633.774649013993;5634.74643641029;5644.85887852807;5464.180372943932;5535.474129957647;5548.667079975215;5549.361823390201;5556.415956835546;5376.892462150813;5518.33779946409;5523.364813046391;5544.61187716856;5544.8394415184575;5429.176828049471;5465.207544906046;5482.205080263259;5524.475174858382;5527.474889885454;5456.945430545644;5501.697406642513;5529.50703310112;5546.750396232099;5570.61005729595;5217.686938783685;5335.279737235423;5446.58388818902;5451.291332126798;5501.559875209893 01:56:38 INFO - PROCESS | 1980 | |5;yui.html;872.1757938747878;881.1496905486208;901.9007909129997;919.7378747057088;924.3760461688363;16302.51092467225;16515.174241287947;16776.161191940384;16878.240479178436;18354.357597484075;16325.83674163258;16525.752113718274;16704.415345462912;16797.07616081115;16966.15169241539;851.6933903794629;856.9571521423935;866.9869951950726;869.8782170496136;880.3353468131584;883.2404132446092;886.0563499872615;886.8758373827669;887.0597167002998;887.201518633232;937.0629370629371;937.6577549194564;939.5161491831713;939.5724945149974;940.2947789158131;928.8606708993705;929.7443203119128;929.7861491856877;930.1163894300374;930.4603330068544;689.4966674327719;691.5954166812421;693.7467824244114;693.7814588404686;693.9757108501228;16760.497185084467;16922.576935576584;17195.484135475956;18665.44003679892;18848.34030808915;14898.255087245594;15790.842091579052;16160.434384796594;16327.102009389479;16498;14988.47540336083;15128.54614361571;15253.771193432107;15327.770083448757;15489.922550387177;4781.2350023996;4876.804927802884;4878.878028049292;4919.606431485508;4949.232868905326;79096;79594;81110.59444702798;83041;90472;12815.935920320338;12855.16441431304;12917.677058073623;12989.545365912149;13072.411741471657;24140.396490087536;24384.756152438604;24409.145679901118;24765.87617061939;25056.37359066038;8976.775580610538;9015.684451044182;9211.815763684688;9289.674861379819;9447.480388578557;4870.902581948341;4898.093852637274;4967.08108999829;5035.093683137071;5136.357955255593;3734.2344819311975;3829.195868867513;3846.807659617008;3856.267309211297;3857.4599556062176;616.8519555306784;623.2116372788427;640.2093414632925;641.6086187425667;644.0243031806816;802.827392110699;813.5606772342868;839.9328053755685;840.9285210757154;842.6713581703141;10924.50839712199;10963.890361096446;11381.203315767816;11516.94241528787;11699.841042918364;811.3671336357651;816.3550794872103;821.7288294862681;827.8716798896181;837.5979529825718;5293.629445938747;5360.758765855546;5367.892642147213;5383.434739352311;5387.272718183073;83031;84049.15950840413;84176;85431.57284213539;88601;44702.55297447049;62307.37692623107;63661;63830.68084659547;71725;64113.67943160254;65199.67400162969;66021.66989165024;66196.6690166546;66441.6677916617;11499.770004600028;12256.693582660504;12269.631911042683;12404.379781011094;12449.34074424872;43740.562594374926;44412.33381499345;44838.327425088;45055.77472112618;46066;51834;51926.48073519216;51927.740361297954;52472.47527524676;53443.465565343846;12230.816537752118;12310.322932238812;12468.937655311665;12536.686582835502;12568.685782855502;15321;15326.233688315762;15472.613184670474;15523.146226957175;15665.608359791097;15145.358391347112;15275.694486109993;15362;15481.5355539334;16315.428959986344;14792.926035369754;14866.628334291296;15015.32431040584;15206;15209;14797.482088126864;14799.704005920037;15115.546533604009;15219.619509512351;15877.444289449813;10395.376277423378;10412.972758201038;10497.895021049997;10902.18233632465;11014;12083.637490875288;12114.21257618252;12168.2699038058;12276;12395.93802030984 01:56:38 INFO - PROCESS | 1980 | __end_tp_report 01:56:38 INFO - PROCESS | 1980 | __start_cc_report 01:56:38 INFO - PROCESS | 1980 | _x_x_mozilla_cycle_collect,1319 01:56:38 INFO - PROCESS | 1980 | __end_cc_report 01:56:38 INFO - PROCESS | 1980 | __startTimestamp1493715398326__endTimestamp 01:56:38 INFO - PROCESS | 1980 | 01:56:38 INFO - PROCESS | 1980 | ------- Summary: start ------- 01:56:38 INFO - PROCESS | 1980 | Number of tests: 6 01:56:38 INFO - PROCESS | 1980 | 01:56:38 INFO - PROCESS | 1980 | [#0] dojo.html Cycles:165 Average:5738.62 Median:5972.46 stddev:664.98 (11.1%) stddev-sans-first:641.81 01:56:38 INFO - PROCESS | 1980 | Values: 3426.9 3435.4 3466.9 3476.1 3521.1 5731.9 5745.6 5768.6 5777.5 5811.4 5777.7 5830.6 5838.8 5846.6 5846.8 5162.1 5167.2 5180.0 5239.0 5250.8 5036.4 5049.6 5258.6 5267.8 5268.5 5126.7 5144.9 5220.7 5239.3 5240.8 5248.7 5249.1 5253.9 5254.8 5280.3 4965.9 5117.6 5181.8 5228.9 5236.4 5602.4 5641.1 5687.3 5700.6 5703.7 6123.5 6175.9 6176.2 6176.6 6185.4 6183.7 6212.0 6215.7 6220.9 6236.8 5584.7 5664.7 5721.3 5810.2 5812.4 6356.6 6357.5 6366.7 6366.9 6375.3 6091.4 6125.9 6135.9 6145.0 6151.8 5967.0 5998.1 6124.6 6127.2 6201.8 6137.8 6149.7 6168.8 6174.4 6198.0 5954.3 6011.5 6086.1 6119.4 6197.6 4554.0 4619.7 4644.3 4646.0 4668.1 4093.4 4110.0 4136.7 4137.7 4230.7 5931.1 5944.8 5952.3 5974.1 5990.1 6190.8 6233.9 6234.8 6237.0 6240.5 5782.9 5835.0 5920.7 5944.9 5960.6 5301.9 5308.2 5334.9 5335.5 5341.1 6555.3 6559.6 6560.2 6569.8 6572.0 5956.0 6060.4 6250.8 6284.4 6285.2 6341.2 6344.0 6438.6 6483.6 6494.5 6086.2 6100.9 6104.8 6105.8 6109.2 5989.5 6211.2 6217.9 6221.8 6221.8 5988.9 6023.5 6025.0 6132.3 6135.0 6020.5 6074.7 6083.1 6084.7 6089.4 5883.4 5952.9 6053.5 6065.5 6076.1 5930.1 6013.3 6086.3 6119.7 6137.8 5956.4 5967.9 5969.3 5972.5 5972.7 01:56:38 INFO - PROCESS | 1980 | 01:56:38 INFO - PROCESS | 1980 | [#1] ext.html Cycles:180 Average:46482.90 Median:31059.50 stddev:80994.09 (260.8%) stddev-sans-first:81148.58 01:56:38 INFO - PROCESS | 1980 | Values: 772.8 818.9 835.9 900.4 900.8 31433.7 43993.8 44761.0 44801.6 44926.3 43354.6 43370.1 43501.6 43591.8 43604.1 7673.0 7720.7 9000.4 9124.9 9128.6 10524.6 10576.5 11083.7 11099.5 11109.3 10864.5 10891.3 10901.3 10905.3 10913.2 10804.6 10825.2 10828.8 10840.1 10848.1 10443.5 10496.4 10501.0 10503.6 10509.4 42185.6 42414.8 42554.1 42624.6 42777.6 4191.7 4239.5 4273.5 4355.3 4388.5 29368.7 29773.9 30066.2 30415.0 31382.7 4281.1 4368.1 4390.9 4399.5 4429.4 231434.0 231702.0 231780.0 232190.0 232319.0 52768.0 52836.5 52855.7 52900.5 52973.2 55336.4 55386.2 55550.6 55660.0 55689.0 16685.0 16886.2 16890.7 16897.8 16917.7 12694.5 12884.7 15004.2 15013.7 15021.5 13127.5 13280.4 13348.5 13449.1 13739.4 5598.9 5604.9 5626.8 5626.9 5800.4 994.0 998.2 1009.6 1021.3 1041.5 28302.2 28746.7 28775.7 28855.7 29015.0 1076.1 1084.2 1095.8 1098.0 1115.3 14391.8 15290.9 15330.3 15370.2 15434.8 291050.0 331092.0 335676.0 446729.0 448750.0 50776.0 50828.5 51100.5 51186.2 51211.7 1421.4 1460.8 1468.3 1480.7 1486.6 26293.0 28634.7 28704.0 28876.1 28942.3 37823.2 37852.6 37896.0 37925.0 37969.8 298858.0 299550.0 300753.0 301135.0 301615.0 29226.3 29294.9 29327.6 30205.4 30358.4 40371.8 40826.2 40853.4 40911.2 41254.8 31538.2 31670.2 31691.2 32051.7 32242.8 31998.7 32005.8 32157.7 32197.0 32412.7 36883.8 36931.4 37499.0 37824.4 37879.2 30749.2 30903.0 30936.0 31183.0 31227.2 24628.5 25057.5 25243.7 25274.0 25468.0 01:56:38 INFO - PROCESS | 1980 | 01:56:38 INFO - PROCESS | 1980 | [#2] jquery.html Cycles:180 Average:37246.20 Median:2238.73 stddev:112193.28 (5011.5%) stddev-sans-first:112473.97 01:56:38 INFO - PROCESS | 1980 | Values: 436.2 470.9 471.7 484.8 491.6 18936.3 20210.4 20252.9 20313.0 20428.2 20372.9 20526.4 20624.7 20681.2 20916.8 4996.0 6479.9 6733.2 6742.1 6750.9 6518.8 6553.1 6581.4 6597.4 6603.1 6373.6 6592.0 6598.4 6621.7 6635.4 6455.5 6526.0 6610.4 6610.4 6674.6 6427.1 6464.1 6470.6 6480.4 6481.3 23157.3 23622.5 23708.3 23832.9 24030.0 3461.2 3515.8 3537.6 3547.0 3627.3 15257.7 15948.9 15951.8 16217.0 16454.4 2177.0 2181.3 2203.4 2238.5 2238.9 77385.0 95618.5 96532.0 97546.0 100150.5 14428.2 17627.8 18514.1 18899.4 18968.3 27327.3 28531.0 35607.8 35660.3 35713.3 10075.1 10082.2 10181.7 10255.7 10301.7 8667.6 8667.7 8707.5 8943.8 8951.6 8860.9 8903.0 9024.9 9098.0 9187.8 3775.9 3869.3 3881.7 3922.5 3925.7 931.9 944.2 946.6 957.9 965.9 19747.6 19870.5 20712.2 21324.1 21423.8 1071.2 1089.7 1092.6 1131.7 1137.8 10455.7 11069.2 11179.4 11230.5 11309.2 663540.0 677077.0 678753.0 689181.0 707419.0 89216.0 91482.5 113755.4 123476.0 125947.4 1322.3 1328.8 1346.6 1362.8 1366.9 13826.0 17817.6 18018.3 19245.3 19344.8 52180.0 53150.0 53706.7 54771.5 54805.5 65689.7 66120.0 66656.7 67541.0 68999.9 11888.6 11964.5 11990.3 12079.6 12132.6 12792.4 12804.7 13008.5 13027.6 13218.9 11495.1 11602.1 11610.2 11630.4 11868.5 8442.7 8665.1 10309.5 10322.3 10500.6 10641.4 11160.2 11309.4 11369.8 11384.4 12048.4 12069.2 12091.6 12147.9 12531.6 10958.6 11110.4 11241.4 11441.3 11518.9 01:56:38 INFO - PROCESS | 1980 | 01:56:38 INFO - PROCESS | 1980 | [#3] mootools.html Cycles:180 Average:7259.02 Median:2420.44 stddev:4637.13 (191.6%) stddev-sans-first:4630.06 01:56:38 INFO - PROCESS | 1980 | Values: 1516.1 1549.5 1576.1 1582.1 1582.7 10954.0 11692.4 11692.6 11697.4 11704.5 11188.4 11445.4 11452.2 11465.7 11519.9 2414.7 2448.5 2452.9 2455.1 2455.4 2358.8 2419.6 2421.3 2422.3 2446.1 2391.1 2432.6 2445.6 2470.7 2473.2 2341.4 2396.2 2405.5 2408.5 2417.7 2369.7 2383.4 2391.1 2392.3 2396.7 10987.1 11028.3 11085.2 11089.7 11093.2 5904.5 6055.2 6060.7 6077.8 6079.0 3397.3 3423.0 3497.2 3616.1 3617.0 1360.3 1390.9 1407.4 1414.4 1429.0 16615.6 16865.2 16942.4 17039.3 17044.6 4922.8 4997.3 5057.0 5070.3 5121.5 13113.9 13141.6 13154.1 13159.5 13160.9 6137.6 6146.3 6147.6 6152.2 6156.4 5498.1 5608.3 5703.2 5757.3 5760.0 3821.6 3937.3 3954.6 3986.8 4017.7 1030.7 1033.3 1044.0 1044.9 1045.2 863.6 875.3 900.6 930.1 1107.0 7145.5 7214.8 7216.0 7278.2 7390.3 1070.8 1094.3 1101.9 1122.3 1160.1 4500.7 4647.4 4816.3 4843.0 4921.8 14799.1 14995.1 15050.9 15405.8 15408.5 15100.8 15116.7 15216.5 15243.2 15285.8 10883.5 10903.3 10949.1 11109.0 11117.9 7066.4 7133.5 7268.5 7286.9 7399.1 13101.8 13105.5 13255.0 13514.9 13541.9 13514.7 13535.2 13678.5 13681.2 13821.7 9135.5 9409.9 9802.0 9914.8 10003.6 11051.8 11165.8 11221.9 11351.1 11397.8 9108.2 9189.8 9351.4 9582.6 9616.6 9149.2 9152.5 9200.3 9326.4 9530.6 8652.0 8656.2 8729.9 8738.4 8747.8 6324.5 6355.6 6448.8 6492.4 6522.0 6530.5 6600.7 6674.5 6681.8 6684.7 01:56:38 INFO - PROCESS | 1980 | 01:56:38 INFO - PROCESS | 1980 | [#4] prototype.html Cycles:180 Average:4769.01 Median:5339.25 stddev:1298.98 (24.3%) stddev-sans-first:1260.89 01:56:38 INFO - PROCESS | 1980 | Values: 417.4 418.2 423.0 425.6 427.8 5201.7 5334.8 5342.9 5346.3 5379.6 5049.6 5157.2 5167.6 5174.0 5174.2 4125.9 4135.7 4151.6 4157.0 4186.2 4185.7 4194.8 4196.2 4201.0 4213.5 3964.3 4054.5 4144.9 4154.7 4181.5 4085.3 4105.6 4121.5 4137.8 4147.9 3733.7 3736.5 3770.0 3774.4 3797.6 4848.0 5073.4 5133.5 5203.8 5224.6 4988.3 4990.4 5008.5 5042.5 5075.0 5508.3 5553.6 5556.2 5572.8 5625.2 4450.6 4498.9 4583.4 4590.1 4610.4 5411.8 5534.2 5612.6 5695.3 5720.5 4628.7 4662.2 4696.7 4708.9 4715.7 4567.9 4766.5 4780.8 4785.7 4799.2 4941.8 4945.4 5029.5 5062.4 5063.7 5442.9 5479.2 5490.2 5500.2 5517.9 1573.4 1618.6 1649.3 1649.4 1661.6 1272.5 1279.9 1284.8 1285.2 1291.3 5386.4 5389.6 5400.9 5406.2 5421.9 5673.3 5681.5 5683.7 5688.8 5697.4 5097.8 5381.0 5385.6 5401.9 5425.2 2938.3 2980.4 2985.7 2986.3 2989.6 5723.7 5875.7 5927.5 5931.6 5949.8 5725.5 5762.3 5770.7 5773.0 5786.2 5857.3 5914.1 5919.3 5953.9 5974.5 5346.9 5614.5 5639.4 5699.8 5705.4 5335.5 5444.0 5454.8 5606.6 5612.3 5927.6 5947.9 5961.9 5968.7 5985.5 4764.5 4775.6 4777.2 4777.5 4796.9 5377.8 5630.9 5633.8 5634.7 5644.9 5464.2 5535.5 5548.7 5549.4 5556.4 5376.9 5518.3 5523.4 5544.6 5544.8 5429.2 5465.2 5482.2 5524.5 5527.5 5456.9 5501.7 5529.5 5546.8 5570.6 5217.7 5335.3 5446.6 5451.3 5501.6 01:56:38 INFO - PROCESS | 1980 | 01:56:38 INFO - PROCESS | 1980 | [#5] yui.html Cycles:180 Average:18536.18 Median:25423.62 stddev:22761.85 (89.5%) stddev-sans-first:22787.05 01:56:38 INFO - PROCESS | 1980 | Values: 872.2 881.1 901.9 919.7 924.4 16302.5 16515.2 16776.2 16878.2 18354.4 16325.8 16525.8 16704.4 16797.1 16966.2 851.7 857.0 867.0 869.9 880.3 883.2 886.1 886.9 887.1 887.2 937.1 937.7 939.5 939.6 940.3 928.9 929.7 929.8 930.1 930.5 689.5 691.6 693.7 693.8 694.0 16760.5 16922.6 17195.5 18665.4 18848.3 14898.3 15790.8 16160.4 16327.1 16498.0 14988.5 15128.5 15253.8 15327.8 15489.9 4781.2 4876.8 4878.9 4919.6 4949.2 79096.0 79594.0 81110.6 83041.0 90472.0 12815.9 12855.2 12917.7 12989.5 13072.4 24140.4 24384.8 24409.1 24765.9 25056.4 8976.8 9015.7 9211.8 9289.7 9447.5 4870.9 4898.1 4967.1 5035.1 5136.4 3734.2 3829.2 3846.8 3856.3 3857.5 616.9 623.2 640.2 641.6 644.0 802.8 813.6 839.9 840.9 842.7 10924.5 10963.9 11381.2 11516.9 11699.8 811.4 816.4 821.7 827.9 837.6 5293.6 5360.8 5367.9 5383.4 5387.3 83031.0 84049.2 84176.0 85431.6 88601.0 44702.6 62307.4 63661.0 63830.7 71725.0 64113.7 65199.7 66021.7 66196.7 66441.7 11499.8 12256.7 12269.6 12404.4 12449.3 43740.6 44412.3 44838.3 45055.8 46066.0 51834.0 51926.5 51927.7 52472.5 53443.5 12230.8 12310.3 12468.9 12536.7 12568.7 15321.0 15326.2 15472.6 15523.1 15665.6 15145.4 15275.7 15362.0 15481.5 16315.4 14792.9 14866.6 15015.3 15206.0 15209.0 14797.5 14799.7 15115.5 15219.6 15877.4 10395.4 10413.0 10497.9 10902.2 11014.0 12083.6 12114.2 12168.3 12276.0 12395.9 01:56:38 INFO - PROCESS | 1980 | -------- Summary: end -------- 01:56:38 INFO - PROCESS | 1980 | 01:56:39 INFO - TEST-INFO | 1980: exit 0 01:56:39 INFO - TEST-OK | dromaeo_css | took 1115330ms 01:56:39 INFO - TEST-START | kraken 01:56:39 INFO - operating with platform_type : linux_ 01:56:39 INFO - Initialising browser for kraken test... 01:56:39 INFO - TEST-INFO | started process 2304 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTAou6a/profile http://localhost:51628/getInfo.html) 01:56:43 INFO - PROCESS | 2304 | __metrics Screen width/height:1600/1200 01:56:43 INFO - PROCESS | 2304 | colorDepth:24 01:56:43 INFO - PROCESS | 2304 | Browser inner width/height: 1024/697 01:56:43 INFO - PROCESS | 2304 | __metrics 01:56:44 INFO - PROCESS | 2304 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:56:44 INFO - PROCESS | 2304 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:56:45 INFO - TEST-INFO | 2304: exit 0 01:56:45 INFO - Browser initialized. 01:56:45 INFO - Running cycle 1/1 for kraken test... 01:56:45 INFO - TEST-INFO | started process 2469 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTAou6a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:56:56 INFO - PROCESS | 2469 | RSS: Main: 140001280 01:56:56 INFO - PROCESS | 2469 | 01:56:59 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51628/tests/kraken/driver.html?testName=audio-beat-detection) 01:57:00 INFO - PROCESS | 2469 | RSS: Main: 182792192 01:57:00 INFO - PROCESS | 2469 | 01:57:02 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51628/tests/kraken/driver.html?testName=audio-dft) 01:57:03 INFO - PROCESS | 2469 | RSS: Main: 174256128 01:57:03 INFO - PROCESS | 2469 | 01:57:06 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51628/tests/kraken/driver.html?testName=audio-fft) 01:57:06 INFO - PROCESS | 2469 | RSS: Main: 265990144 01:57:06 INFO - PROCESS | 2469 | 01:57:08 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51628/tests/kraken/driver.html?testName=audio-oscillator) 01:57:09 INFO - PROCESS | 2469 | RSS: Main: 172322816 01:57:09 INFO - PROCESS | 2469 | 01:57:11 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51628/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:57:11 INFO - PROCESS | 2469 | RSS: Main: 174313472 01:57:11 INFO - PROCESS | 2469 | 01:57:14 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51628/tests/kraken/driver.html?testName=imaging-darkroom) 01:57:15 INFO - PROCESS | 2469 | RSS: Main: 209616896 01:57:15 INFO - PROCESS | 2469 | 01:57:18 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51628/tests/kraken/driver.html?testName=imaging-desaturate) 01:57:19 INFO - PROCESS | 2469 | RSS: Main: 212127744 01:57:19 INFO - PROCESS | 2469 | 01:57:22 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51628/tests/kraken/driver.html?testName=json-parse-financial) 01:57:22 INFO - PROCESS | 2469 | RSS: Main: 210759680 01:57:22 INFO - PROCESS | 2469 | 01:57:24 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51628/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:57:25 INFO - PROCESS | 2469 | RSS: Main: 173240320 01:57:25 INFO - PROCESS | 2469 | 01:57:26 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51628/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:57:27 INFO - PROCESS | 2469 | RSS: Main: 179482624 01:57:27 INFO - PROCESS | 2469 | 01:57:29 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51628/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:57:29 INFO - PROCESS | 2469 | RSS: Main: 188493824 01:57:29 INFO - PROCESS | 2469 | 01:57:32 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:57:33 INFO - PROCESS | 2469 | RSS: Main: 198529024 01:57:33 INFO - PROCESS | 2469 | 01:57:35 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:57:36 INFO - PROCESS | 2469 | RSS: Main: 194719744 01:57:36 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | Cycle 1(1): loaded http://localhost:51628/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51628/tests/kraken/driver.html?testName=ai-astar) 01:57:38 INFO - PROCESS | 2469 | RSS: Main: 245497856 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | __start_tp_report 01:57:38 INFO - PROCESS | 2469 | _x_x_mozilla_page_load 01:57:38 INFO - PROCESS | 2469 | _x_x_mozilla_page_load_details 01:57:38 INFO - PROCESS | 2469 | |i|pagename|runs| 01:57:38 INFO - PROCESS | 2469 | |0;ai-astar;102;111;112;111;110;111;112;112;110;110 01:57:38 INFO - PROCESS | 2469 | |1;audio-beat-detection;139;117;115;157;116;121;114;122;140;116 01:57:38 INFO - PROCESS | 2469 | |2;audio-dft;161;162;161;162;161;161;161;161;162;161 01:57:38 INFO - PROCESS | 2469 | |3;audio-fft;75;75;76;107;75;76;78;76;89;76 01:57:38 INFO - PROCESS | 2469 | |4;audio-oscillator;81;82;109;81;82;82;97;81;81;101 01:57:38 INFO - PROCESS | 2469 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;114;113;112 01:57:38 INFO - PROCESS | 2469 | |6;imaging-darkroom;147;146;146;147;146;147;146;147;146;145 01:57:38 INFO - PROCESS | 2469 | |7;imaging-desaturate;95;94;94;94;95;94;94;94;94;95 01:57:38 INFO - PROCESS | 2469 | |8;json-parse-financial;56;53;53;54;53;55;52;55;53;54 01:57:38 INFO - PROCESS | 2469 | |9;json-stringify-tinderbox;52;52;52;53;53;53;52;52;52;53 01:57:38 INFO - PROCESS | 2469 | |10;stanford-crypto-aes;76;76;80;75;76;86;74;80;75;75 01:57:38 INFO - PROCESS | 2469 | |11;stanford-crypto-ccm;121;120;120;129;128;127;125;130;166;134 01:57:38 INFO - PROCESS | 2469 | |12;stanford-crypto-pbkdf2;165;159;159;159;160;166;158;159;160;158 01:57:38 INFO - PROCESS | 2469 | |13;stanford-crypto-sha256-iterative;66;62;60;64;65;59;59;63;62;58 01:57:38 INFO - PROCESS | 2469 | __end_tp_report 01:57:38 INFO - PROCESS | 2469 | __start_cc_report 01:57:38 INFO - PROCESS | 2469 | _x_x_mozilla_cycle_collect,1648 01:57:38 INFO - PROCESS | 2469 | __end_cc_report 01:57:38 INFO - PROCESS | 2469 | __startTimestamp1493715458286__endTimestamp 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | ------- Summary: start ------- 01:57:38 INFO - PROCESS | 2469 | Number of tests: 14 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.00 stddev:2.96 (2.7%) stddev-sans-first:0.87 01:57:38 INFO - PROCESS | 2469 | Values: 102.0 111.0 112.0 111.0 110.0 111.0 112.0 112.0 110.0 110.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:121.50 stddev:14.58 (12.0%) stddev-sans-first:14.64 01:57:38 INFO - PROCESS | 2469 | Values: 139.0 117.0 115.0 157.0 116.0 121.0 114.0 122.0 140.0 116.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#2] audio-dft Cycles:10 Average:161.30 Median:161.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 01:57:38 INFO - PROCESS | 2469 | Values: 161.0 162.0 161.0 162.0 161.0 161.0 161.0 161.0 162.0 161.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#3] audio-fft Cycles:10 Average:80.30 Median:76.00 stddev:10.29 (13.5%) stddev-sans-first:10.73 01:57:38 INFO - PROCESS | 2469 | Values: 75.0 75.0 76.0 107.0 75.0 76.0 78.0 76.0 89.0 76.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:81.50 stddev:10.51 (12.9%) stddev-sans-first:10.86 01:57:38 INFO - PROCESS | 2469 | Values: 81.0 82.0 109.0 81.0 82.0 82.0 97.0 81.0 81.0 101.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 01:57:38 INFO - PROCESS | 2469 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 114.0 113.0 112.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 01:57:38 INFO - PROCESS | 2469 | Values: 147.0 146.0 146.0 147.0 146.0 147.0 146.0 147.0 146.0 145.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 01:57:38 INFO - PROCESS | 2469 | Values: 95.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 95.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.23 (2.3%) stddev-sans-first:1.01 01:57:38 INFO - PROCESS | 2469 | Values: 56.0 53.0 53.0 54.0 53.0 55.0 52.0 55.0 53.0 54.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#9] json-stringify-tinderbox Cycles:10 Average:52.40 Median:52.50 stddev:0.52 (1.0%) stddev-sans-first:0.53 01:57:38 INFO - PROCESS | 2469 | Values: 52.0 52.0 52.0 53.0 53.0 53.0 52.0 52.0 52.0 53.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#10] stanford-crypto-aes Cycles:10 Average:77.30 Median:76.00 stddev:3.68 (4.8%) stddev-sans-first:3.88 01:57:38 INFO - PROCESS | 2469 | Values: 76.0 76.0 80.0 75.0 76.0 86.0 74.0 80.0 75.0 75.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#11] stanford-crypto-ccm Cycles:10 Average:130.00 Median:128.50 stddev:13.47 (10.5%) stddev-sans-first:13.88 01:57:38 INFO - PROCESS | 2469 | Values: 121.0 120.0 120.0 129.0 128.0 127.0 125.0 130.0 166.0 134.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.30 Median:159.50 stddev:2.83 (1.8%) stddev-sans-first:2.44 01:57:38 INFO - PROCESS | 2469 | Values: 165.0 159.0 159.0 159.0 160.0 166.0 158.0 159.0 160.0 158.0 01:57:38 INFO - PROCESS | 2469 | 01:57:38 INFO - PROCESS | 2469 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.50 stddev:2.74 (4.4%) stddev-sans-first:2.45 01:57:38 INFO - PROCESS | 2469 | Values: 66.0 62.0 60.0 64.0 65.0 59.0 59.0 63.0 62.0 58.0 01:57:38 INFO - PROCESS | 2469 | -------- Summary: end -------- 01:57:38 INFO - PROCESS | 2469 | 01:57:39 INFO - TEST-INFO | 2469: exit 0 01:57:39 INFO - TEST-OK | kraken | took 60192ms 01:57:39 INFO - SUITE-END | took 1175s 01:57:39 INFO - Completed test suite (00:19:35) 01:57:39 INFO - javascript benchmark 01:57:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7817.070232937839, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3426.9143271418197, 3435.4159792835217, 3466.8959931202066, 3476.130967258184, 3521.084518025314, 5731.856703582413, 5745.569082318827, 5768.596198266123, 5777.508911742507, 5811.389804070575, 5777.682227477487, 5830.591858569902, 5838.824835254949, 5846.6199697019665, 5846.795362162326, 5162.070827251112, 5167.199084141964, 5179.974100129513, 5239.0, 5250.7637156328055, 5036.395632524084, 5049.596032317406, 5258.55302299305, 5267.841964741064, 5268.525832675058, 5126.692398456086, 5144.8971020579565, 5220.738963051832, 5239.266502689627, 5240.764165612538, 5248.685078895279, 5249.081410753122, 5253.921191182136, 5254.8160814371695, 5280.313559237313, 4965.90068198634, 5117.641765076446, 5181.792728290864, 5228.895422091537, 5236.371635403738, 5602.439756024406, 5641.069223578104, 5687.289088863892, 5700.572457065737, 5703.7148142592705, 6123.479504242145, 6175.876482470326, 6176.227971503562, 6176.598521096114, 6185.381461853824, 6183.721732522047, 6212.0, 6215.689215539205, 6220.937790622081, 6236.844078898064, 5584.664920104807, 5664.688442135681, 5721.284839395117, 5810.244668193108, 5812.418758124239, 6356.586821856657, 6357.523185760994, 6366.681665916685, 6366.904496432558, 6375.2987171411105, 6091.360407157274, 6125.877482450326, 6135.938640613536, 6145.0, 6151.815445536641, 5967.0, 5998.100284957204, 6124.594542075475, 6127.234095738033, 6201.782937597162, 6137.8060289513105, 6149.66176860268, 6168.784092556829, 6174.38256174388, 6197.96901015492, 5954.285485741739, 6011.549133814894, 6086.087086936995, 6119.418655227746, 6197.628142311386, 4554.017283138437, 4619.745913974763, 4644.303354496785, 4645.976770116195, 4668.089722504079, 4093.3655283431117, 4110.0135967368215, 4136.710430269913, 4137.668986481074, 4230.746155230696, 5931.110333450017, 5944.7919322824355, 5952.315483719428, 5974.103884417372, 5990.101484777231, 6190.7523699051535, 6233.937660623426, 6234.781782637586, 6236.968815155986, 6240.500759939194, 5782.942170578241, 5835.008048631743, 5920.733566989496, 5944.940550594439, 5960.612560183574, 5301.920471192935, 5308.177232528964, 5334.946650533445, 5335.519803217729, 5341.118715411992, 6555.311692272338, 6559.6064236145985, 6560.212774467094, 6569.83575410609, 6571.967140164268, 5956.047032474957, 6060.393960603904, 6250.842246589816, 6284.402981716729, 6285.2457705075685, 6341.239051313872, 6344.048392741126, 6438.6458744767615, 6483.643399613066, 6494.480441564758, 6086.178365920544, 6100.908486372797, 6104.755809767737, 6105.84735381619, 6109.236345456818, 5989.460948514655, 6211.223597050369, 6217.906731398942, 6221.844453888508, 6221.844453888689, 5988.910166347421, 6023.548233882389, 6025.0, 6132.264128304632, 6134.969325153345, 6020.54845886569, 6074.726637301421, 6083.117947897618, 6084.69576521181, 6089.421504957022, 5883.441073098049, 5952.880942381042, 6053.545984051126, 6065.4541091302, 6076.088586712029, 5930.11048342752, 6013.30846952606, 6086.300075491198, 6119.746288422617, 6137.7851775187655, 5956.374580668968, 5967.880642387041, 5969.253843269591, 5972.462478376967, 5972.701364931823], "value": 5693.810702990986, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.7990722412175, 818.9058258300296, 835.8620827563452, 900.3607438718509, 900.7522931193924, 31433.685663143362, 43993.78003109984, 44761.0, 44801.551984480146, 44926.32610510841, 43354.56645433545, 43370.132597348034, 43501.564984350145, 43591.78204108983, 43604.127917441714, 7673.0, 7720.6911723531275, 9000.414973026765, 9124.908750912473, 9128.634854605807, 10524.631637892675, 10576.471176441186, 11083.72290692731, 11099.500522476508, 11109.277896936674, 10864.45677716115, 10891.292066015683, 10901.291416057971, 10905.291156074869, 10913.23607347486, 10804.621838235664, 10825.188110891715, 10828.783424331508, 10840.078593319498, 10848.132149428027, 10443.477826108665, 10496.422696751675, 10501.0, 10503.632372866912, 10509.421981790998, 42185.578144218474, 42414.78792606017, 42554.1489170218, 42624.57375426268, 42777.57222427798, 4191.7275377100395, 4239.47006624172, 4273.465816772903, 4355.303151495746, 4388.495323037828, 29368.70631293681, 29773.851130744424, 30066.24834379136, 30415.0, 31382.686173138434, 4281.143771245764, 4368.104538569585, 4390.8902277443, 4399.490029681884, 4429.424174857248, 231434.0, 231702.0, 231780.0, 232190.0, 232319.0, 52768.0, 52836.47163528315, 52855.735721321915, 52900.47099528956, 52973.205401919, 55336.44663553313, 55386.16920746192, 55550.61123471946, 55660.0, 55689.0, 16685.0, 16886.15569221534, 16890.662186756195, 16897.83102168987, 16917.661646766996, 12694.49222031127, 12884.677883052998, 15004.249787510798, 15013.69972600542, 15021.549353519413, 13127.474901004043, 13280.402381892838, 13348.532801351907, 13449.125806822722, 13739.381727822272, 5598.888022239533, 5604.85987850307, 5626.831195064155, 5626.859328516738, 5800.4489573490455, 993.9950300248453, 998.1765043838863, 1009.6264382178641, 1021.2595867995643, 1041.5156952017348, 28302.150935471556, 28746.712532874404, 28775.712242877304, 28855.711442885302, 29015.0, 1076.1444651502075, 1084.2356138922041, 1095.8246680531236, 1098.0337303173158, 1115.2973626615342, 14391.78412323816, 15290.923545382424, 15330.310136043905, 15370.154641494824, 15434.768478472832, 291050.0, 331092.0, 335676.0, 446729.0, 448750.0, 50776.0, 50828.49171508312, 51100.48899511032, 51186.232206516936, 51211.74394128005, 1421.3562773241763, 1460.832004319517, 1468.258529442646, 1480.6668499587722, 1486.5614643679924, 26293.0, 28634.713652863204, 28704.0, 28876.133715988555, 28942.276443089093, 37823.243535129695, 37852.62147378491, 37896.0, 37925.0, 37969.810150949066, 298858.0, 299550.0, 300753.0, 301135.0, 301615.0, 29226.269343266587, 29294.853525732236, 29327.560086599144, 30205.395892082473, 30358.392832142792, 40371.79814100911, 40826.1834763309, 40853.38719919263, 40911.1817763649, 41254.79372603118, 31538.211544711565, 31670.208244794063, 31691.207719806265, 32051.67948320487, 32242.83878580592, 31998.68001319957, 32005.839970799996, 32157.678423215468, 32197.0, 32412.675873240965, 36883.815580921924, 36931.44602830906, 37499.0, 37824.43263351107, 37879.2424151521, 30749.23126921845, 30903.0, 30936.0, 31183.0, 31227.219319517193, 24628.507429850943, 25057.49885002326, 25243.747562524874, 25274.0, 25468.0], "value": 18852.11005410246, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.1908659436743, 470.88698712309036, 471.67218782945855, 484.79880849447505, 491.6018025399427, 18936.337228197015, 20210.393688189342, 20252.898735506322, 20312.99999999998, 20428.182872685113, 20372.898135509302, 20526.384208473733, 20624.69062964057, 20681.172753089857, 20916.790832091676, 4996.0, 6479.93520064798, 6733.192016957972, 6742.089817874597, 6750.898736518956, 6518.771842985497, 6553.082568440422, 6581.440577550914, 6597.406233438989, 6603.141591593087, 6373.553851230416, 6591.967040164793, 6598.37315455031, 6621.73513059477, 6635.43598794103, 6455.451286640641, 6526.021096835465, 6610.438112760421, 6610.438112760421, 6674.56615320005, 6427.068075129127, 6464.095026696266, 6470.579412338183, 6480.4491618212505, 6481.28705842357, 23157.305280841603, 23622.527549448914, 23708.288751337488, 23832.88083559571, 24030.0, 3461.152017755641, 3515.789052656849, 3537.6285490023433, 3546.982265088684, 3627.3470775260444, 15257.703095236919, 15948.920255398765, 15951.760723589156, 16217.0, 16454.424095156613, 2177.0203408466255, 2181.3128864407663, 2203.3500117465387, 2238.507528343762, 2238.947694583543, 77385.0, 95618.5219073907, 96532.0, 97546.0, 100150.49924750402, 14428.207824369392, 17627.82699114172, 18514.056024117544, 18899.433017009513, 18968.336108236144, 27327.316817079336, 28531.0, 35607.82196089003, 35660.28679426397, 35713.28573428517, 10075.09324160829, 10082.19342452602, 10181.694549163536, 10255.7436064099, 10301.690949271533, 8667.566621668891, 8667.696630617898, 8707.47755134694, 8943.821123577492, 8951.641934322684, 8860.911390886138, 8902.955485222661, 9024.8646270306, 9097.954510227539, 9187.77030574241, 3775.8867233983024, 3869.28418242626, 3881.7476864003747, 3922.5489068756888, 3925.685945124383, 931.9161275485238, 944.1927152284835, 946.6450081219542, 957.9281553883486, 965.9082387173207, 19747.60504789896, 19870.50323741918, 20712.171513139307, 21324.12124363222, 21423.785762142492, 1071.2019545438698, 1089.6567581211893, 1092.6121226964312, 1131.660501849442, 1137.7724455109012, 10455.68632941013, 11069.169812263952, 11179.385133817717, 11230.494627741771, 11309.208355415041, 663540.0, 677077.0, 678753.0, 689181.0, 707419.0, 89216.0, 91482.54258728797, 113755.43122284336, 123476.0, 125947.3702631481, 1322.3322222277689, 1328.8307386882354, 1346.5556366399064, 1362.768329384007, 1366.9043166978415, 13826.0, 17817.554561136076, 18018.2792688291, 19245.326413575458, 19344.806551934864, 52180.0, 53150.0, 53706.73146634242, 54771.452285478226, 54805.45194548163, 65689.67155164384, 66120.0, 66656.6667166661, 67541.0, 68999.89500472591, 11888.643340699793, 11964.46159922823, 11990.280583165038, 12079.637610871689, 12132.636020919386, 12792.36038198105, 12804.743905122032, 13008.47966081384, 13027.609171724864, 13218.867811322149, 11495.080393568327, 11602.129840262183, 11610.24533405326, 11630.418479076181, 11868.525258989544, 8442.66229350819, 8665.133486651284, 10309.48452577353, 10322.329048611817, 10500.579976800842, 10641.414722190353, 11160.218784684994, 11309.434528273718, 11369.829452558384, 11384.43077846121, 12048.397580121135, 12069.155159139127, 12091.637250882488, 12147.939260303641, 12531.62152163243, 10958.616448424267, 11110.388928608681, 11241.437928103725, 11441.313521188755, 11518.884811151782], "value": 11121.291383498916, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1516.109912988041, 1549.4576898085663, 1576.0543673795723, 1582.1140161509556, 1582.6676397956426, 10954.0, 11692.415379231026, 11692.649220523377, 11697.415129243536, 11704.473298701558, 11188.440577971109, 11445.427728613578, 11452.198346115776, 11465.713357166074, 11519.884801152008, 2414.7464516225764, 2448.52253810507, 2452.9386765330833, 2455.1407007547305, 2455.4475243070256, 2358.7523310052457, 2419.5523828091773, 2421.32202983165, 2422.34596658902, 2446.107170882633, 2391.1272385579314, 2432.62294344376, 2445.596476581362, 2470.703515578133, 2473.183849329726, 2341.4263505441277, 2396.2451827674226, 2405.518896220754, 2408.5303365843665, 2417.6857008588945, 2369.7037870266217, 2383.3564937466786, 2391.1152873436768, 2392.306231192952, 2396.6524853896262, 10987.066099381562, 11028.338299702044, 11085.168612354106, 11089.667309980714, 11093.16801239902, 5904.498117660004, 6055.152278680988, 6060.727267272983, 6077.817665469999, 6078.969605151946, 3397.252604427022, 3422.9657703422895, 3497.2306092659574, 3616.1140520572367, 3617.04148400674, 1360.2994457854213, 1390.9468716168014, 1407.3596513586294, 1414.3847426369562, 1428.9857101428956, 16615.584610384718, 16865.156742162966, 16942.407015754514, 17039.318427262893, 17044.57388565271, 4922.827701030446, 4997.250412438163, 5057.039162559102, 5070.340855688737, 5121.4622464641425, 13113.934430327978, 13141.605751827461, 13154.079214455087, 13159.473621055242, 13160.934195329153, 6137.631742095488, 6146.323904370515, 6147.631142131487, 6152.2002139722445, 6156.415140561639, 5498.147787093007, 5608.327000759935, 5703.17303990919, 5757.309122905278, 5760.0207964646115, 3821.6178382162116, 3937.3109705801944, 3954.6440820325734, 3986.8405263789705, 4017.7187596868525, 1030.701096681969, 1033.3283365812283, 1044.0290529807353, 1044.9007344302279, 1045.153425725165, 863.5509535041743, 875.3085062800444, 900.6127365233012, 930.0745757970863, 1106.9612563560397, 7145.4640901931525, 7214.819629509199, 7216.0, 7278.163011253668, 7390.29792169743, 1070.844727514506, 1094.2941802537318, 1101.9063379612662, 1122.2705241592867, 1160.0545555372344, 4500.684952053325, 4647.419072615923, 4816.349792778, 4842.95157048425, 4921.803127874917, 14799.112053276838, 14995.100293982396, 15050.924745376204, 15405.768913466307, 15408.537743867477, 15100.773488397683, 15116.697666046617, 15216.467423640119, 15243.23783810805, 15285.847141528442, 10883.455827208609, 10903.34579925207, 10949.124070074216, 11109.00018998294, 11117.888821111846, 7066.434685225273, 7133.500654954103, 7268.527545709512, 7286.854262914606, 7399.075115610549, 13101.803472948104, 13105.475780968654, 13255.0, 13514.932425338204, 13541.932290338485, 13514.662133446742, 13535.187888726314, 13678.452861885413, 13681.179129252278, 13821.654458638212, 9135.497547634945, 9409.905900941178, 9802.019798020141, 9914.801703966024, 10003.649872254728, 11051.778964420828, 11165.776684466102, 11221.88778112241, 11351.14866385041, 11397.829032564685, 9108.225800807006, 9189.816203676022, 9351.438913665203, 9582.568784404857, 9616.615335386787, 9149.222316103205, 9152.542372881197, 9200.263978881807, 9326.393784403992, 9530.618775248913, 8652.0, 8656.220940115169, 8729.869051964353, 8738.388312818042, 8747.781305467413, 6324.462420694293, 6355.618662880242, 6448.806535803933, 6492.415682588589, 6521.9673901630185, 6530.47756179514, 6600.702968366339, 6674.466042716668, 6681.799546013627, 6684.732610695518], "value": 5469.931847646319, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.351019165198, 418.18036648169584, 422.9598188172124, 425.6148185891767, 427.7606728774863, 5201.739913004349, 5334.839954801352, 5342.946570534294, 5346.2782524359245, 5379.596530260226, 5049.596032317415, 5157.21510384392, 5167.638265321429, 5173.974130129344, 5174.172132458798, 4125.876223713293, 4135.73117747347, 4151.626353628173, 4156.97921510392, 4186.204621121992, 4185.714214341727, 4194.804480722994, 4196.181744559812, 4201.0, 4213.452251207339, 3964.2864284428792, 4054.493188351456, 4144.896377590554, 4154.729942553739, 4181.540030596632, 4085.346344584852, 4105.609967053125, 4121.5476791309275, 4137.81379837908, 4147.8963025924295, 3733.7386382953205, 3736.4582135590185, 3769.963260103466, 3774.358359078963, 3797.582265950743, 4847.951520484785, 5073.39119305682, 5133.537981581639, 5203.765830537597, 5224.63427560071, 4988.251762235694, 4990.426100998396, 5008.524190201925, 5042.546170844642, 5075.0, 5508.338999320067, 5553.555715542747, 5556.249906262643, 5572.832815015556, 5625.17181915879, 4450.577195166454, 4498.932516012263, 4583.381243532114, 4590.104929538741, 4610.377599024156, 5411.810586629488, 5534.169874518814, 5612.635178713412, 5695.288088988877, 5720.542356611461, 4628.745419001987, 4662.207424737802, 4696.7416792076765, 4708.88227794301, 4715.669903106749, 4567.898766567416, 4766.475687674353, 4780.808767649255, 4785.688930219594, 4799.208130658402, 4941.777620007108, 4945.406551213805, 5029.497050295015, 5062.443131255559, 5063.670861393997, 5442.863928401743, 5479.232907392889, 5490.203920431515, 5500.167413781905, 5517.862053448696, 1573.3785154863995, 1618.6034421566792, 1649.2743192995044, 1649.364994477135, 1661.5929097371222, 1272.5048068519452, 1279.929603871796, 1284.800855867342, 1285.190330092036, 1291.3156027305542, 5386.444946335399, 5389.649672771257, 5400.91898621521, 5406.216098665672, 5421.918671219935, 5673.34069525154, 5681.545476361881, 5683.744231509592, 5688.800891968761, 5697.4302569742695, 5097.796088156432, 5380.973095134499, 5385.5960802939935, 5401.864953376197, 5425.209680805325, 2938.2654336415894, 2980.389020250887, 2985.7163569460868, 2986.25343664084, 2989.5515672649276, 5723.710515793633, 5875.735591898375, 5927.4665280125, 5931.58478906481, 5949.8215053548465, 5725.5133313667975, 5762.279715035621, 5770.711464426762, 5772.971135144381, 5786.2477877876445, 5857.326407463111, 5914.142449344821, 5919.260092488439, 5953.851153721192, 5974.492168165581, 5346.9465305348, 5614.522765564972, 5639.351474580311, 5699.800506982236, 5705.429457054262, 5335.546478549367, 5443.972780136074, 5454.809081682122, 5606.607537472501, 5612.298462692163, 5927.585069045125, 5947.851303717269, 5961.880762384815, 5968.731407086605, 5985.521158307412, 4764.49972752856, 4775.6179505638775, 4777.235642297216, 4777.474477807369, 4796.856094317176, 5377.758000889891, 5630.88738225225, 5633.774649013993, 5634.74643641029, 5644.85887852807, 5464.180372943932, 5535.474129957647, 5548.667079975215, 5549.361823390201, 5556.415956835546, 5376.892462150813, 5518.33779946409, 5523.364813046391, 5544.61187716856, 5544.8394415184575, 5429.176828049471, 5465.207544906046, 5482.205080263259, 5524.475174858382, 5527.474889885454, 5456.945430545644, 5501.697406642513, 5529.50703310112, 5546.750396232099, 5570.61005729595, 5217.686938783685, 5335.279737235423, 5446.58388818902, 5451.291332126798, 5501.559875209893], "value": 4395.807042782843, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [872.1757938747878, 881.1496905486208, 901.9007909129997, 919.7378747057088, 924.3760461688363, 16302.51092467225, 16515.174241287947, 16776.161191940384, 16878.240479178436, 18354.357597484075, 16325.83674163258, 16525.752113718274, 16704.415345462912, 16797.07616081115, 16966.15169241539, 851.6933903794629, 856.9571521423935, 866.9869951950726, 869.8782170496136, 880.3353468131584, 883.2404132446092, 886.0563499872615, 886.8758373827669, 887.0597167002998, 887.201518633232, 937.0629370629371, 937.6577549194564, 939.5161491831713, 939.5724945149974, 940.2947789158131, 928.8606708993705, 929.7443203119128, 929.7861491856877, 930.1163894300374, 930.4603330068544, 689.4966674327719, 691.5954166812421, 693.7467824244114, 693.7814588404686, 693.9757108501228, 16760.497185084467, 16922.576935576584, 17195.484135475956, 18665.44003679892, 18848.34030808915, 14898.255087245594, 15790.842091579052, 16160.434384796594, 16327.102009389479, 16498.0, 14988.47540336083, 15128.54614361571, 15253.771193432107, 15327.770083448757, 15489.922550387177, 4781.2350023996, 4876.804927802884, 4878.878028049292, 4919.606431485508, 4949.232868905326, 79096.0, 79594.0, 81110.59444702798, 83041.0, 90472.0, 12815.935920320338, 12855.16441431304, 12917.677058073623, 12989.545365912149, 13072.411741471657, 24140.396490087536, 24384.756152438604, 24409.145679901118, 24765.87617061939, 25056.37359066038, 8976.775580610538, 9015.684451044182, 9211.815763684688, 9289.674861379819, 9447.480388578557, 4870.902581948341, 4898.093852637274, 4967.08108999829, 5035.093683137071, 5136.357955255593, 3734.2344819311975, 3829.195868867513, 3846.807659617008, 3856.267309211297, 3857.4599556062176, 616.8519555306784, 623.2116372788427, 640.2093414632925, 641.6086187425667, 644.0243031806816, 802.827392110699, 813.5606772342868, 839.9328053755685, 840.9285210757154, 842.6713581703141, 10924.50839712199, 10963.890361096446, 11381.203315767816, 11516.94241528787, 11699.841042918364, 811.3671336357651, 816.3550794872103, 821.7288294862681, 827.8716798896181, 837.5979529825718, 5293.629445938747, 5360.758765855546, 5367.892642147213, 5383.434739352311, 5387.272718183073, 83031.0, 84049.15950840413, 84176.0, 85431.57284213539, 88601.0, 44702.55297447049, 62307.37692623107, 63661.0, 63830.68084659547, 71725.0, 64113.67943160254, 65199.67400162969, 66021.66989165024, 66196.6690166546, 66441.6677916617, 11499.770004600028, 12256.693582660504, 12269.631911042683, 12404.379781011094, 12449.34074424872, 43740.562594374926, 44412.33381499345, 44838.327425088, 45055.77472112618, 46066.0, 51834.0, 51926.48073519216, 51927.740361297954, 52472.47527524676, 53443.465565343846, 12230.816537752118, 12310.322932238812, 12468.937655311665, 12536.686582835502, 12568.685782855502, 15321.0, 15326.233688315762, 15472.613184670474, 15523.146226957175, 15665.608359791097, 15145.358391347112, 15275.694486109993, 15362.0, 15481.5355539334, 16315.428959986344, 14792.926035369754, 14866.628334291296, 15015.32431040584, 15206.0, 15209.0, 14797.482088126864, 14799.704005920037, 15115.546533604009, 15219.619509512351, 15877.444289449813, 10395.376277423378, 10412.972758201038, 10497.895021049997, 10902.18233632465, 11014.0, 12083.637490875288, 12114.21257618252, 12168.2699038058, 12276.0, 12395.93802030984], "value": 7948.542399540706, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 112.0, 111.0, 110.0, 111.0, 112.0, 112.0, 110.0, 110.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 117.0, 115.0, 157.0, 116.0, 121.0, 114.0, 122.0, 140.0, 116.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 162.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0], "value": 161.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 107.0, 75.0, 76.0, 78.0, 76.0, 89.0, 76.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 109.0, 81.0, 82.0, 82.0, 97.0, 81.0, 81.0, 101.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 112.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 145.0], "value": 146.3, "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, 94.0, 95.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 54.0, 53.0, 55.0, 52.0, 55.0, 53.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 53.0, 53.0, 53.0, 52.0, 52.0, 52.0, 53.0], "value": 52.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 80.0, 75.0, 76.0, 86.0, 74.0, 80.0, 75.0, 75.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 120.0, 129.0, 128.0, 127.0, 125.0, 130.0, 166.0, 134.0], "value": 130.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 159.0, 159.0, 159.0, 160.0, 166.0, 158.0, 159.0, 160.0, 158.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 62.0, 60.0, 64.0, 65.0, 59.0, 59.0, 63.0, 62.0, 58.0], "value": 61.8, "unit": "score"}]}]} 01:57:39 INFO - Return code: 0 01:57:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:57:39 ERROR - # TBPL SUCCESS # 01:57:39 INFO - Running post-action listener: _resource_record_post_action 01:57:39 INFO - [mozharness: 2017-05-02 08:57:39.622845Z] Finished run-tests step (success) 01:57:39 INFO - Running post-run listener: _resource_record_post_run 01:57:39 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 274432; Write bytes: 365105152; Read time: 280; Write time: 241408 01:57:39 INFO - TinderboxPrint: CPU usage
13.1% 01:57:39 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 280 01:57:39 INFO - TinderboxPrint: I/O write bytes / time
365,105,152 / 241,408 01:57:39 INFO - TinderboxPrint: CPU idle
8,262.3 (86.8%) 01:57:39 INFO - TinderboxPrint: CPU user
1,202.9 (12.6%) 01:57:39 INFO - TinderboxPrint: Swap in / out
0 / 0 01:57:39 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 111075328; Read time: 60; Write time: 95860 01:57:39 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 270336; Write bytes: 254029824; Read time: 220; Write time: 145548 01:57:40 INFO - Running post-run listener: _upload_blobber_files 01:57:40 INFO - Blob upload gear active. 01:57:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:57:40 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 01:57:40 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', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:57:40 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 Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:57:40 INFO - (blobuploader) - INFO - Open directory for files ... 01:57:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:57:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:57:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:41 INFO - (blobuploader) - INFO - Done attempting. 01:57:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:57:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:57:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:44 INFO - (blobuploader) - INFO - Done attempting. 01:57:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:57:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:57:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:45 INFO - (blobuploader) - INFO - Done attempting. 01:57:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:57:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:57:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:46 INFO - (blobuploader) - INFO - Done attempting. 01:57:46 INFO - (blobuploader) - INFO - Iteration through files over. 01:57:46 INFO - Return code: 0 01:57:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:57:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:57:46 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ce3db2558fb6ae691305b44b184ad956a27c84956c9f628a7773be47edbea1bc07fc5e265af1013e2b0482fd878d6c8944a3d20131a979ad49e02399fb4e8273", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/15366ab93b2bdf3be5bb92412f51d1fef69ec3a347ca3ff090428fb80908079eb823e5ef118ea97156ce05d744b6ea87e1cd79c02808e69a3329bf546fe199b2", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/3f52d18258572fb9acefdc9e65b2ecba1f981281e732ee87ad623afa53a1160586d8861761af967c7ce7314b1ae53f59d6d35641193930212b96329d8a9627c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dc8c097b11bf03bb4e54c136342d1910a35e60c81cbaa40563c94175b7be95439cc4a5f54421d05bc0720aaef049d3f192aa57166ae254bb294dbda7b2f59111"} 01:57:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:57:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:57:46 INFO - Contents: 01:57:46 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ce3db2558fb6ae691305b44b184ad956a27c84956c9f628a7773be47edbea1bc07fc5e265af1013e2b0482fd878d6c8944a3d20131a979ad49e02399fb4e8273", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/15366ab93b2bdf3be5bb92412f51d1fef69ec3a347ca3ff090428fb80908079eb823e5ef118ea97156ce05d744b6ea87e1cd79c02808e69a3329bf546fe199b2", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/3f52d18258572fb9acefdc9e65b2ecba1f981281e732ee87ad623afa53a1160586d8861761af967c7ce7314b1ae53f59d6d35641193930212b96329d8a9627c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dc8c097b11bf03bb4e54c136342d1910a35e60c81cbaa40563c94175b7be95439cc4a5f54421d05bc0720aaef049d3f192aa57166ae254bb294dbda7b2f59111"} 01:57:46 INFO - Running post-run listener: copy_logs_to_upload_dir 01:57:46 INFO - Copying logs to upload dir... 01:57:46 INFO - mkdir: /builds/slave/test/build/upload/logs 01:57:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.901674 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-05-02 01:57:45.940538) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-02 01:57:45.947069) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ce3db2558fb6ae691305b44b184ad956a27c84956c9f628a7773be47edbea1bc07fc5e265af1013e2b0482fd878d6c8944a3d20131a979ad49e02399fb4e8273", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/15366ab93b2bdf3be5bb92412f51d1fef69ec3a347ca3ff090428fb80908079eb823e5ef118ea97156ce05d744b6ea87e1cd79c02808e69a3329bf546fe199b2", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/3f52d18258572fb9acefdc9e65b2ecba1f981281e732ee87ad623afa53a1160586d8861761af967c7ce7314b1ae53f59d6d35641193930212b96329d8a9627c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dc8c097b11bf03bb4e54c136342d1910a35e60c81cbaa40563c94175b7be95439cc4a5f54421d05bc0720aaef049d3f192aa57166ae254bb294dbda7b2f59111"} build_url:https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011313 build_url: 'https://queue.taskcluster.net/v1/task/RCdtVbohQyGDx_EFPjuKCw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/ce3db2558fb6ae691305b44b184ad956a27c84956c9f628a7773be47edbea1bc07fc5e265af1013e2b0482fd878d6c8944a3d20131a979ad49e02399fb4e8273", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/15366ab93b2bdf3be5bb92412f51d1fef69ec3a347ca3ff090428fb80908079eb823e5ef118ea97156ce05d744b6ea87e1cd79c02808e69a3329bf546fe199b2", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/3f52d18258572fb9acefdc9e65b2ecba1f981281e732ee87ad623afa53a1160586d8861761af967c7ce7314b1ae53f59d6d35641193930212b96329d8a9627c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dc8c097b11bf03bb4e54c136342d1910a35e60c81cbaa40563c94175b7be95439cc4a5f54421d05bc0720aaef049d3f192aa57166ae254bb294dbda7b2f59111"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-02 01:57:45.971861) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 01:57:45.972169) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1493710215.88648-1751293102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004394 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-02 01:57:46.057773) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 01:57:46.058074) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-02 01:57:46.061425) ========= ========= Total master_lag: 0.17 =========