builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-019 starttime: 1491940030.92 results: success (0) revision: 2bd14ae23d1842853c1abf500abb6c9613dd3c17 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:10.923993) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:10.924449) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:10.924730) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:10.939498) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:10.939813) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-11 12:47:11-- 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% 112M=0s 2017-04-11 12:47:11 (112 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.372956 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:11.326677) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:11.327020) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018026 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:11.376990) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:11.377361) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2bd14ae23d1842853c1abf500abb6c9613dd3c17 --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 2bd14ae23d1842853c1abf500abb6c9613dd3c17 --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=78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-11 12:47:11,733 truncating revision to first 12 chars 2017-04-11 12:47:11,733 Setting DEBUG logging. 2017-04-11 12:47:11,733 attempt 1/10 2017-04-11 12:47:11,733 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2bd14ae23d18?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 12:47:12,267 unpacking tar archive at: mozilla-aurora-2bd14ae23d18/testing/mozharness/ program finished with exit code 0 elapsedTime=0.836818 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:12.227546) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:12.227858) ========= script_repo_revision: 2bd14ae23d1842853c1abf500abb6c9613dd3c17 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:12.228258) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:12.228538) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 12:47:12.238370) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-11 12:47:12.238695) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:47:12 INFO - MultiFileLogger online at 20170411 12:47:12 in /builds/slave/test 12:47:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:47:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:47:12 INFO - {'append_to_log': False, 12:47:12 INFO - 'base_work_dir': '/builds/slave/test', 12:47:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:47:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:47:12 INFO - 'branch': 'Mozilla-Aurora', 12:47:12 INFO - 'buildbot_json_path': 'buildprops.json', 12:47:12 INFO - 'config_files': ('talos/linux_config.py',), 12:47:12 INFO - 'default_actions': ('clobber', 12:47:12 INFO - 'read-buildbot-config', 12:47:12 INFO - 'download-and-extract', 12:47:12 INFO - 'populate-webroot', 12:47:12 INFO - 'create-virtualenv', 12:47:12 INFO - 'install', 12:47:12 INFO - 'run-tests'), 12:47:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:47:12 INFO - 'download_minidump_stackwalk': True, 12:47:12 INFO - 'download_symbols': 'ondemand', 12:47:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:47:12 INFO - 'tooltool.py': '/tools/tooltool.py', 12:47:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:47:12 INFO - '/tools/misc-python/virtualenv.py')}, 12:47:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:47:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:47:12 INFO - 'gecko_profile': False, 12:47:12 INFO - 'gecko_profile_interval': 0, 12:47:12 INFO - 'installer_path': 'installer.exe', 12:47:12 INFO - 'log_level': 'info', 12:47:12 INFO - 'log_name': 'talos', 12:47:12 INFO - 'log_to_console': True, 12:47:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:47:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:47:12 INFO - 'opt_config_files': (), 12:47:12 INFO - 'pip_index': False, 12:47:12 INFO - 'suite': 'dromaeojs', 12:47:12 INFO - 'system_bits': '32', 12:47:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:47:12 INFO - 'title': 'talos-linux64-ix-019', 12:47:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:47:12 INFO - 'use_talos_json': True, 12:47:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:47:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:47:12 INFO - 'work_dir': 'build'} 12:47:12 INFO - [mozharness: 2017-04-11 19:47:12.696278Z] Running clobber step. 12:47:12 INFO - Running pre-action listener: _resource_record_pre_action 12:47:12 INFO - Running main action method: clobber 12:47:12 INFO - rmtree: /builds/slave/test/build 12:47:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:47:13 INFO - Running post-action listener: _resource_record_post_action 12:47:13 INFO - [mozharness: 2017-04-11 19:47:13.153507Z] Finished clobber step (success) 12:47:13 INFO - [mozharness: 2017-04-11 19:47:13.153609Z] Running read-buildbot-config step. 12:47:13 INFO - Running pre-action listener: _resource_record_pre_action 12:47:13 INFO - Running main action method: read_buildbot_config 12:47:13 INFO - Using buildbot properties: 12:47:13 INFO - { 12:47:13 INFO - "project": "", 12:47:13 INFO - "product": "firefox", 12:47:13 INFO - "who": "mozilla@noorenberghe.ca", 12:47:13 INFO - "installer_path": "public/build/target.tar.bz2", 12:47:13 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 12:47:13 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 12:47:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 12:47:13 INFO - "stage_platform": "linux64-pgo", 12:47:13 INFO - "basedir": "/builds/slave/test", 12:47:13 INFO - "buildnumber": 831, 12:47:13 INFO - "platform": "ubuntu64_hw", 12:47:13 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:47:13 INFO - "slavebuilddir": "test", 12:47:13 INFO - "taskId": "euJssA0OTf636oKiZ4Hu2g", 12:47:13 INFO - "branch": "mozilla-aurora", 12:47:13 INFO - "script_repo_revision": "production", 12:47:13 INFO - "revision": "2bd14ae23d1842853c1abf500abb6c9613dd3c17", 12:47:13 INFO - "slavename": "talos-linux64-ix-019", 12:47:13 INFO - "repo_path": "releases/mozilla-aurora" 12:47:13 INFO - } 12:47:13 INFO - Finding installer, test and symbols from parent task. 12:47:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/euJssA0OTf636oKiZ4Hu2g'}, attempt #1 12:47:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/euJssA0OTf636oKiZ4Hu2g'}, attempt #1 12:47:14 INFO - Task dependencies: NSWdPTxlTeK0L1F7Zqsozw 12:47:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw'}, attempt #1 12:47:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.tar.bz2 12:47:14 INFO - Set test_url: https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/test_packages.json 12:47:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.crashreporter-symbols.zip 12:47:14 INFO - Running post-action listener: _resource_record_post_action 12:47:14 INFO - [mozharness: 2017-04-11 19:47:14.636921Z] Finished read-buildbot-config step (success) 12:47:14 INFO - [mozharness: 2017-04-11 19:47:14.637139Z] Running download-and-extract step. 12:47:14 INFO - Running pre-action listener: _resource_record_pre_action 12:47:14 INFO - Running main action method: download_and_extract 12:47:14 INFO - mkdir: /builds/slave/test/build/tests 12:47:14 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')]} 12:47:14 INFO - trying https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.test_packages.json 12:47:14 INFO - Downloading https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:47:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:47:15 INFO - Downloaded 896 bytes. 12:47:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:47:15 INFO - Using the following test package requirements: 12:47:15 INFO - {u'common': [u'target.common.tests.zip'], 12:47:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:47:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:47:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:47:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:47:15 INFO - u'mozbase': [u'target.common.tests.zip'], 12:47:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:47:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:47:15 INFO - u'web-platform': [u'target.common.tests.zip', 12:47:15 INFO - u'target.web-platform.tests.zip'], 12:47:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:47:15 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:47:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.common.tests.zip 12:47:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:47:15 INFO - Fetch https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.common.tests.zip into memory 12:47:16 INFO - Expected file size: 59541667 12:47:16 INFO - Obtained file size: 59541667 12:47:26 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:47:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.common.tests.zip 12:47:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:47:26 INFO - Fetch https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.common.tests.zip into memory 12:47:27 INFO - Expected file size: 59541667 12:47:27 INFO - Obtained file size: 59541667 12:47:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.talos.tests.zip 12:47:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:47:37 INFO - Fetch https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.talos.tests.zip into memory 12:47:38 INFO - Expected file size: 14404424 12:47:38 INFO - Obtained file size: 14404424 12:47: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')]} 12:47:38 INFO - trying https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.tar.bz2 12:47:38 INFO - Downloading https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:47:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:47:39 INFO - Downloaded 66690728 bytes. 12:47:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.tar.bz2 12:47:39 INFO - mkdir: /builds/slave/test/properties 12:47:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:47:39 INFO - Writing to file /builds/slave/test/properties/build_url 12:47:39 INFO - Contents: 12:47:39 INFO - build_url:https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.tar.bz2 12:47:39 INFO - Running post-action listener: _resource_record_post_action 12:47:39 INFO - Running post-action listener: set_extra_try_arguments 12:47:39 INFO - [mozharness: 2017-04-11 19:47:39.919782Z] Finished download-and-extract step (success) 12:47:39 INFO - [mozharness: 2017-04-11 19:47:39.919880Z] Running populate-webroot step. 12:47:39 INFO - Running pre-action listener: _resource_record_pre_action 12:47:39 INFO - Running main action method: populate_webroot 12:47:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:47:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:47:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:47:39 INFO - '--tppagecycles', 12:47:39 INFO - '7'], 12:47:39 INFO - 'tests': ['tsvgm']}}, 12:47:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:47:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:47:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:47:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:47:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:47:39 INFO - 'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:47:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:47:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:47:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:47:39 INFO - 'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['damp', 'tps']}, 12:47:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:47:39 INFO - 'tests': ['damp', 'tps']}, 12:47:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['dromaeo_dom']}, 12:47:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:47:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['basic_compositor_video']}, 12:47:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:47:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['a11yr', 12:47:39 INFO - 'ts_paint', 12:47:39 INFO - 'tpaint', 12:47:39 INFO - 'sessionrestore', 12:47:39 INFO - 'sessionrestore_no_auto_restore', 12:47:39 INFO - 'tabpaint']}, 12:47:39 INFO - 'other-e10s': {'tests': ['a11yr', 12:47:39 INFO - 'ts_paint', 12:47:39 INFO - 'tpaint', 12:47:39 INFO - 'sessionrestore', 12:47:39 INFO - 'sessionrestore_no_auto_restore', 12:47:39 INFO - 'tabpaint']}, 12:47:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:47:39 INFO - 'ts_paint', 12:47:39 INFO - 'tpaint', 12:47:39 INFO - 'sessionrestore', 12:47:39 INFO - 'sessionrestore_no_auto_restore', 12:47:39 INFO - 'tabpaint']}, 12:47:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:47:39 INFO - 'ts_paint', 12:47:39 INFO - 'tpaint', 12:47:39 INFO - 'sessionrestore', 12:47:39 INFO - 'sessionrestore_no_auto_restore', 12:47:39 INFO - 'tabpaint']}, 12:47:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['a11yr', 12:47:39 INFO - 'ts_paint', 12:47:39 INFO - 'tpaint', 12:47:39 INFO - 'sessionrestore', 12:47:39 INFO - 'sessionrestore_no_auto_restore', 12:47:39 INFO - 'tabpaint']}, 12:47:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['a11yr', 12:47:39 INFO - 'ts_paint', 12:47:39 INFO - 'tpaint', 12:47:39 INFO - 'sessionrestore', 12:47:39 INFO - 'sessionrestore_no_auto_restore', 12:47:39 INFO - 'tabpaint']}, 12:47:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['tsvgx', 12:47:39 INFO - 'tsvgr_opacity', 12:47:39 INFO - 'tart', 12:47:39 INFO - 'tscrollx', 12:47:39 INFO - 'cart', 12:47:39 INFO - 'tsvg_static']}, 12:47:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:47:39 INFO - 'tsvgr_opacity', 12:47:39 INFO - 'tart', 12:47:39 INFO - 'tscrollx', 12:47:39 INFO - 'cart', 12:47:39 INFO - 'tsvg_static']}, 12:47:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:47:39 INFO - 'talos_options': ['--disable-e10s'], 12:47:39 INFO - 'tests': ['tp5o']}, 12:47:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:47:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:47:39 INFO - 'talos_options': ['--disable-e10s', 12:47:39 INFO - '--xperf_path', 12:47:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:47:39 INFO - 'tests': ['tp5n']}, 12:47:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:47:39 INFO - 'talos_options': ['--xperf_path', 12:47:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:47:39 INFO - 'tests': ['tp5n']}}, 12:47:39 INFO - 'talos.zip': {'path': '', 12:47:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:47:39 INFO - Running post-action listener: _resource_record_post_action 12:47:39 INFO - [mozharness: 2017-04-11 19:47:39.933096Z] Finished populate-webroot step (success) 12:47:39 INFO - [mozharness: 2017-04-11 19:47:39.933186Z] Running create-virtualenv step. 12:47:39 INFO - Running pre-action listener: _resource_record_pre_action 12:47:39 INFO - Running main action method: create_virtualenv 12:47:39 INFO - Creating virtualenv /builds/slave/test/build/venv 12:47:39 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 12:47:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:47:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:47:40 INFO - Using real prefix '/usr' 12:47:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:47:40 INFO - Installing distribute.............................................................................................................................................................................................done. 12:47:42 INFO - Installing pip.................done. 12:47:42 INFO - Return code: 0 12:47:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:47:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:47:42 INFO - Reading from file tmpfile_stdout 12:47:42 INFO - Output received: 12:47:42 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) 12:47:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:47: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')]} 12:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47: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')]} 12:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:42 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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:47:42 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 12:47:42 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 12:47:42 INFO - Using env: {'DISPLAY': ':0', 12:47:42 INFO - 'HOME': '/home/cltbld', 12:47:42 INFO - 'LANG': 'en_US.UTF-8', 12:47:42 INFO - 'LANGUAGE': 'en_US:en', 12:47:42 INFO - 'LOGNAME': 'cltbld', 12:47:42 INFO - 'MAIL': '/var/mail/cltbld', 12:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:42 INFO - 'MOZ_NO_REMOTE': '1', 12:47:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:47:42 INFO - 'NO_EM_RESTART': '1', 12:47:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:42 INFO - 'PWD': '/builds/slave/test', 12:47:42 INFO - 'SHELL': '/bin/bash', 12:47:42 INFO - 'SHLVL': '1', 12:47:42 INFO - 'TERM': 'linux', 12:47:42 INFO - 'TMOUT': '86400', 12:47:42 INFO - 'USER': 'cltbld', 12:47:42 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:42 INFO - '_': '/tools/buildbot/bin/python'} 12:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:42 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 12:47:42 INFO - Cleaning up... 12:47:42 INFO - Return code: 0 12:47:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:47: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')]} 12:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47: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')]} 12:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:42 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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:47:42 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 12:47:42 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 12:47:42 INFO - Using env: {'DISPLAY': ':0', 12:47:42 INFO - 'HOME': '/home/cltbld', 12:47:42 INFO - 'LANG': 'en_US.UTF-8', 12:47:42 INFO - 'LANGUAGE': 'en_US:en', 12:47:42 INFO - 'LOGNAME': 'cltbld', 12:47:43 INFO - 'MAIL': '/var/mail/cltbld', 12:47:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:43 INFO - 'MOZ_NO_REMOTE': '1', 12:47:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:47:43 INFO - 'NO_EM_RESTART': '1', 12:47:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:47:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:43 INFO - 'PWD': '/builds/slave/test', 12:47:43 INFO - 'SHELL': '/bin/bash', 12:47:43 INFO - 'SHLVL': '1', 12:47:43 INFO - 'TERM': 'linux', 12:47:43 INFO - 'TMOUT': '86400', 12:47:43 INFO - 'USER': 'cltbld', 12:47:43 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:47:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:43 INFO - '_': '/tools/buildbot/bin/python'} 12:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:43 INFO - Downloading/unpacking psutil>=3.1.1 12:47: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 12:47: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 12:47: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 12:47: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 12:47: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 12:47: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 12:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:47:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:47:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:47:45 INFO - Installing collected packages: psutil 12:47:45 INFO - Running setup.py install for psutil 12:47:45 INFO - building 'psutil._psutil_linux' extension 12:47:45 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 12:47:45 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 12:47:45 INFO - building 'psutil._psutil_posix' extension 12:47:45 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 12:47:45 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 12:47:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:47:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:47:45 INFO - Successfully installed psutil 12:47:45 INFO - Cleaning up... 12:47:45 INFO - Return code: 0 12:47:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:47:45 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')]} 12:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:45 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')]} 12:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:45 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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:47:45 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 12:47:45 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 12:47:45 INFO - Using env: {'DISPLAY': ':0', 12:47:45 INFO - 'HOME': '/home/cltbld', 12:47:45 INFO - 'LANG': 'en_US.UTF-8', 12:47:45 INFO - 'LANGUAGE': 'en_US:en', 12:47:45 INFO - 'LOGNAME': 'cltbld', 12:47:45 INFO - 'MAIL': '/var/mail/cltbld', 12:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:45 INFO - 'MOZ_NO_REMOTE': '1', 12:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:47:45 INFO - 'NO_EM_RESTART': '1', 12:47:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:45 INFO - 'PWD': '/builds/slave/test', 12:47:45 INFO - 'SHELL': '/bin/bash', 12:47:45 INFO - 'SHLVL': '1', 12:47:45 INFO - 'TERM': 'linux', 12:47:45 INFO - 'TMOUT': '86400', 12:47:45 INFO - 'USER': 'cltbld', 12:47:45 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:45 INFO - '_': '/tools/buildbot/bin/python'} 12:47:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:47:46 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 12:47:46 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 12:47:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:47:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:47:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:47:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:47:48 INFO - Installing collected packages: mozsystemmonitor 12:47:48 INFO - Running setup.py install for mozsystemmonitor 12:47:48 INFO - Successfully installed mozsystemmonitor 12:47:48 INFO - Cleaning up... 12:47:48 INFO - Return code: 0 12:47:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:47:48 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')]} 12:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:48 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')]} 12:47:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:48 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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:47:48 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 12:47:48 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 12:47:48 INFO - Using env: {'DISPLAY': ':0', 12:47:48 INFO - 'HOME': '/home/cltbld', 12:47:48 INFO - 'LANG': 'en_US.UTF-8', 12:47:48 INFO - 'LANGUAGE': 'en_US:en', 12:47:48 INFO - 'LOGNAME': 'cltbld', 12:47:48 INFO - 'MAIL': '/var/mail/cltbld', 12:47:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:48 INFO - 'MOZ_NO_REMOTE': '1', 12:47:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:47:48 INFO - 'NO_EM_RESTART': '1', 12:47:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:47:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:48 INFO - 'PWD': '/builds/slave/test', 12:47:48 INFO - 'SHELL': '/bin/bash', 12:47:48 INFO - 'SHLVL': '1', 12:47:48 INFO - 'TERM': 'linux', 12:47:48 INFO - 'TMOUT': '86400', 12:47:48 INFO - 'USER': 'cltbld', 12:47:48 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:47:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:48 INFO - '_': '/tools/buildbot/bin/python'} 12:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:48 INFO - Downloading/unpacking jsonschema==2.5.1 12:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:47:48 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 12:47:48 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 12:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:47:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:47:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:47:50 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 12:47:50 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 12:47:50 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 12:47:50 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 12:47:50 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 12:47:50 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 12:47:50 INFO - Downloading functools32-3.2.3-2.tar.gz 12:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:47:50 INFO - warning: no files found matching '*.txt' 12:47:50 INFO - no previously-included directories found matching 'build' 12:47:50 INFO - no previously-included directories found matching 'dist' 12:47:50 INFO - no previously-included directories found matching '.git*' 12:47:50 INFO - Installing collected packages: jsonschema, functools32 12:47:50 INFO - Running setup.py install for functools32 12:47:50 INFO - warning: no files found matching '*.txt' 12:47:50 INFO - no previously-included directories found matching 'build' 12:47:50 INFO - no previously-included directories found matching 'dist' 12:47:50 INFO - no previously-included directories found matching '.git*' 12:47:50 INFO - Successfully installed jsonschema functools32 12:47:50 INFO - Cleaning up... 12:47:51 INFO - Return code: 0 12:47:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:47:51 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')]} 12:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:51 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')]} 12:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:51 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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:47:51 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 12:47:51 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 12:47:51 INFO - Using env: {'DISPLAY': ':0', 12:47:51 INFO - 'HOME': '/home/cltbld', 12:47:51 INFO - 'LANG': 'en_US.UTF-8', 12:47:51 INFO - 'LANGUAGE': 'en_US:en', 12:47:51 INFO - 'LOGNAME': 'cltbld', 12:47:51 INFO - 'MAIL': '/var/mail/cltbld', 12:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:51 INFO - 'MOZ_NO_REMOTE': '1', 12:47:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:47:51 INFO - 'NO_EM_RESTART': '1', 12:47:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:51 INFO - 'PWD': '/builds/slave/test', 12:47:51 INFO - 'SHELL': '/bin/bash', 12:47:51 INFO - 'SHLVL': '1', 12:47:51 INFO - 'TERM': 'linux', 12:47:51 INFO - 'TMOUT': '86400', 12:47:51 INFO - 'USER': 'cltbld', 12:47:51 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:51 INFO - '_': '/tools/buildbot/bin/python'} 12:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:47:51 INFO - Cleaning up... 12:47:51 INFO - Return code: 0 12:47:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:47:51 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')]} 12:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:51 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')]} 12:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:51 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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:47:51 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 12:47:51 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 12:47:51 INFO - Using env: {'DISPLAY': ':0', 12:47:51 INFO - 'HOME': '/home/cltbld', 12:47:51 INFO - 'LANG': 'en_US.UTF-8', 12:47:51 INFO - 'LANGUAGE': 'en_US:en', 12:47:51 INFO - 'LOGNAME': 'cltbld', 12:47:51 INFO - 'MAIL': '/var/mail/cltbld', 12:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:51 INFO - 'MOZ_NO_REMOTE': '1', 12:47:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:47:51 INFO - 'NO_EM_RESTART': '1', 12:47:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:51 INFO - 'PWD': '/builds/slave/test', 12:47:51 INFO - 'SHELL': '/bin/bash', 12:47:51 INFO - 'SHLVL': '1', 12:47:51 INFO - 'TERM': 'linux', 12:47:51 INFO - 'TMOUT': '86400', 12:47:51 INFO - 'USER': 'cltbld', 12:47:51 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:51 INFO - '_': '/tools/buildbot/bin/python'} 12:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:51 INFO - Downloading/unpacking blobuploader==1.2.4 12:47:51 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 12:47:51 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 12:47:51 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 12:47:51 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 12:47:51 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 12:47:51 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 12:47:53 INFO - Downloading blobuploader-1.2.4.tar.gz 12:47:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:47:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:47:53 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 12:47:53 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 12:47:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:47:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:47:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:47:54 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 12:47:54 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 12:47:54 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 12:47:54 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 12:47:54 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 12:47:54 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 12:47:54 INFO - Downloading docopt-0.6.1.tar.gz 12:47:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:47:54 INFO - Installing collected packages: blobuploader, requests, docopt 12:47:54 INFO - Running setup.py install for blobuploader 12:47:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:47:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:47:54 INFO - Running setup.py install for requests 12:47:54 INFO - Running setup.py install for docopt 12:47:54 INFO - Successfully installed blobuploader requests docopt 12:47:54 INFO - Cleaning up... 12:47:54 INFO - Return code: 0 12:47:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:47:54 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')]} 12:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:54 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')]} 12:47:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:54 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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:47:54 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 12:47:54 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 12:47:54 INFO - Using env: {'DISPLAY': ':0', 12:47:54 INFO - 'HOME': '/home/cltbld', 12:47:54 INFO - 'LANG': 'en_US.UTF-8', 12:47:54 INFO - 'LANGUAGE': 'en_US:en', 12:47:54 INFO - 'LOGNAME': 'cltbld', 12:47:54 INFO - 'MAIL': '/var/mail/cltbld', 12:47:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:54 INFO - 'MOZ_NO_REMOTE': '1', 12:47:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:47:54 INFO - 'NO_EM_RESTART': '1', 12:47:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:47:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:54 INFO - 'PWD': '/builds/slave/test', 12:47:54 INFO - 'SHELL': '/bin/bash', 12:47:54 INFO - 'SHLVL': '1', 12:47:54 INFO - 'TERM': 'linux', 12:47:54 INFO - 'TMOUT': '86400', 12:47:54 INFO - 'USER': 'cltbld', 12:47:54 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:47:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:54 INFO - '_': '/tools/buildbot/bin/python'} 12:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:47:55 INFO - Running setup.py (path:/tmp/pip-__w1ga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:47:55 INFO - Running setup.py (path:/tmp/pip-GwqYlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:47:55 INFO - Running setup.py (path:/tmp/pip-yEogP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:47:55 INFO - Running setup.py (path:/tmp/pip-VUZJk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:47:55 INFO - Running setup.py (path:/tmp/pip-AJOdtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:47:55 INFO - Running setup.py (path:/tmp/pip-oNDgHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:47:55 INFO - Running setup.py (path:/tmp/pip-EeO_nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:47:55 INFO - Running setup.py (path:/tmp/pip-1WsynL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:47:55 INFO - Running setup.py (path:/tmp/pip-Ay1XuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:47:55 INFO - Running setup.py (path:/tmp/pip-5xsRrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:47:55 INFO - Running setup.py (path:/tmp/pip-JQuGHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:47:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:47:55 INFO - Running setup.py (path:/tmp/pip-SEzqJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:47:56 INFO - Running setup.py (path:/tmp/pip-0VgaSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:47:56 INFO - Running setup.py (path:/tmp/pip-ZKspp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:47:56 INFO - Running setup.py (path:/tmp/pip-PD31Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:47:56 INFO - Running setup.py (path:/tmp/pip-X4iVnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:47:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:47:56 INFO - Running setup.py (path:/tmp/pip-E6cult-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:47:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:47:56 INFO - Running setup.py install for manifestparser 12:47:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:47:56 INFO - Running setup.py install for mozcrash 12:47:56 INFO - Running setup.py install for mozdebug 12:47:56 INFO - Running setup.py install for mozdevice 12:47:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:47:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:47:56 INFO - Running setup.py install for mozfile 12:47:56 INFO - Running setup.py install for mozhttpd 12:47:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:47:56 INFO - Running setup.py install for mozinfo 12:47:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Running setup.py install for mozInstall 12:47:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Running setup.py install for mozleak 12:47:57 INFO - Running setup.py install for mozlog 12:47:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Running setup.py install for moznetwork 12:47:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Running setup.py install for mozprocess 12:47:57 INFO - Running setup.py install for mozprofile 12:47:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Running setup.py install for mozrunner 12:47:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:47:57 INFO - Running setup.py install for mozscreenshot 12:47:57 INFO - Running setup.py install for moztest 12:47:58 INFO - Running setup.py install for mozversion 12:47:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:47:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:47:58 INFO - Cleaning up... 12:47:58 INFO - Return code: 0 12:47:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:47:58 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')]} 12:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:58 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')]} 12:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:58 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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:47:58 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 12:47:58 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 12:47:58 INFO - Using env: {'DISPLAY': ':0', 12:47:58 INFO - 'HOME': '/home/cltbld', 12:47:58 INFO - 'LANG': 'en_US.UTF-8', 12:47:58 INFO - 'LANGUAGE': 'en_US:en', 12:47:58 INFO - 'LOGNAME': 'cltbld', 12:47:58 INFO - 'MAIL': '/var/mail/cltbld', 12:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:58 INFO - 'MOZ_NO_REMOTE': '1', 12:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:47:58 INFO - 'NO_EM_RESTART': '1', 12:47:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:58 INFO - 'PWD': '/builds/slave/test', 12:47:58 INFO - 'SHELL': '/bin/bash', 12:47:58 INFO - 'SHLVL': '1', 12:47:58 INFO - 'TERM': 'linux', 12:47:58 INFO - 'TMOUT': '86400', 12:47:58 INFO - 'USER': 'cltbld', 12:47:58 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:58 INFO - '_': '/tools/buildbot/bin/python'} 12:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:47:58 INFO - Running setup.py (path:/tmp/pip-SyDE3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:47:58 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)) 12:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:47:58 INFO - Running setup.py (path:/tmp/pip-V7icsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:47:58 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)) 12:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:47:58 INFO - Running setup.py (path:/tmp/pip-YUlWlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:47:58 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)) 12:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:47:58 INFO - Running setup.py (path:/tmp/pip-AHmuXI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:47:58 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)) 12:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:47:58 INFO - Running setup.py (path:/tmp/pip-EoRkoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:47:58 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)) 12:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:47:58 INFO - Running setup.py (path:/tmp/pip-j74_TD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:47:58 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)) 12:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:47:58 INFO - Running setup.py (path:/tmp/pip-Ii3KOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:47:58 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)) 12:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:47:58 INFO - Running setup.py (path:/tmp/pip-EGF42W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:47:58 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)) 12:47:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:47:58 INFO - Running setup.py (path:/tmp/pip-25iRGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:47:59 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)) 12:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:47:59 INFO - Running setup.py (path:/tmp/pip-J1iT07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:47:59 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)) 12:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:47:59 INFO - Running setup.py (path:/tmp/pip-NfQwzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:47:59 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)) 12:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:47:59 INFO - Running setup.py (path:/tmp/pip-BZ1Mz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:47:59 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)) 12:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:47:59 INFO - Running setup.py (path:/tmp/pip-Axn3yW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:47:59 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)) 12:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:47:59 INFO - Running setup.py (path:/tmp/pip-AFlM_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:47:59 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)) 12:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:47:59 INFO - Running setup.py (path:/tmp/pip-aBYp8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:47:59 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)) 12:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:47:59 INFO - Running setup.py (path:/tmp/pip-ZHZp3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:47:59 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)) 12:47:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:47:59 INFO - Running setup.py (path:/tmp/pip-tVGZnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:47:59 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)) 12:47:59 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)) 12:47:59 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)) 12:47:59 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)) 12:47:59 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)) 12:47:59 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)) 12:47:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:47:59 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 12:47:59 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 12:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:01 INFO - Downloading blessings-1.6.tar.gz 12:48:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:48:01 INFO - Installing collected packages: blessings 12:48:01 INFO - Running setup.py install for blessings 12:48:01 INFO - Successfully installed blessings 12:48:01 INFO - Cleaning up... 12:48:01 INFO - Return code: 0 12:48:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:48:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:48:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:48:02 INFO - Reading from file tmpfile_stdout 12:48:02 INFO - Current package versions: 12:48:02 INFO - argparse == 1.2.1 12:48:02 INFO - blessings == 1.6 12:48:02 INFO - blobuploader == 1.2.4 12:48:02 INFO - docopt == 0.6.1 12:48:02 INFO - functools32 == 3.2.3-2 12:48:02 INFO - jsonschema == 2.5.1 12:48:02 INFO - manifestparser == 1.1 12:48:02 INFO - mozInstall == 1.12 12:48:02 INFO - mozcrash == 1.0 12:48:02 INFO - mozdebug == 0.1 12:48:02 INFO - mozdevice == 0.49 12:48:02 INFO - mozfile == 1.2 12:48:02 INFO - mozhttpd == 0.7 12:48:02 INFO - mozinfo == 0.9 12:48:02 INFO - mozleak == 0.1 12:48:02 INFO - mozlog == 3.4 12:48:02 INFO - moznetwork == 0.27 12:48:02 INFO - mozprocess == 0.25 12:48:02 INFO - mozprofile == 0.28 12:48:02 INFO - mozrunner == 6.13 12:48:02 INFO - mozscreenshot == 0.1 12:48:02 INFO - mozsystemmonitor == 0.3 12:48:02 INFO - moztest == 0.8 12:48:02 INFO - mozversion == 1.4 12:48:02 INFO - psutil == 3.1.1 12:48:02 INFO - requests == 1.2.3 12:48:02 INFO - wsgiref == 0.1.2 12:48:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48:02 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')]} 12:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:02 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')]} 12:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:02 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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:48:02 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 12:48:02 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 12:48:02 INFO - Using env: {'DISPLAY': ':0', 12:48:02 INFO - 'HOME': '/home/cltbld', 12:48:02 INFO - 'LANG': 'en_US.UTF-8', 12:48:02 INFO - 'LANGUAGE': 'en_US:en', 12:48:02 INFO - 'LOGNAME': 'cltbld', 12:48:02 INFO - 'MAIL': '/var/mail/cltbld', 12:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:02 INFO - 'MOZ_NO_REMOTE': '1', 12:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:48:02 INFO - 'NO_EM_RESTART': '1', 12:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:02 INFO - 'PWD': '/builds/slave/test', 12:48:02 INFO - 'SHELL': '/bin/bash', 12:48:02 INFO - 'SHLVL': '1', 12:48:02 INFO - 'TERM': 'linux', 12:48:02 INFO - 'TMOUT': '86400', 12:48:02 INFO - 'USER': 'cltbld', 12:48:02 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:02 INFO - '_': '/tools/buildbot/bin/python'} 12:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:02 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)) 12:48:02 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)) 12:48:02 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)) 12:48:02 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)) 12:48:02 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)) 12:48:02 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)) 12:48:02 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)) 12:48:02 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)) 12:48:02 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)) 12:48:02 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)) 12:48:02 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)) 12:48:02 INFO - Cleaning up... 12:48:02 INFO - Return code: 0 12:48:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:48:02 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')]} 12:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:02 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')]} 12:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1b971f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', '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 12:48:02 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 12:48:02 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 12:48:02 INFO - Using env: {'DISPLAY': ':0', 12:48:02 INFO - 'HOME': '/home/cltbld', 12:48:02 INFO - 'LANG': 'en_US.UTF-8', 12:48:02 INFO - 'LANGUAGE': 'en_US:en', 12:48:02 INFO - 'LOGNAME': 'cltbld', 12:48:02 INFO - 'MAIL': '/var/mail/cltbld', 12:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:02 INFO - 'MOZ_NO_REMOTE': '1', 12:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:48:02 INFO - 'NO_EM_RESTART': '1', 12:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:02 INFO - 'PWD': '/builds/slave/test', 12:48:02 INFO - 'SHELL': '/bin/bash', 12:48:02 INFO - 'SHLVL': '1', 12:48:02 INFO - 'TERM': 'linux', 12:48:02 INFO - 'TMOUT': '86400', 12:48:02 INFO - 'USER': 'cltbld', 12:48:02 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:02 INFO - '_': '/tools/buildbot/bin/python'} 12:48:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:48:02 INFO - Cleaning up... 12:48:02 INFO - Return code: 0 12:48:02 INFO - Running post-action listener: _resource_record_post_action 12:48:02 INFO - Running post-action listener: _start_resource_monitoring 12:48:02 INFO - Starting resource monitoring. 12:48:02 INFO - [mozharness: 2017-04-11 19:48:02.629335Z] Finished create-virtualenv step (success) 12:48:02 INFO - [mozharness: 2017-04-11 19:48:02.629855Z] Running install step. 12:48:02 INFO - Running pre-action listener: _resource_record_pre_action 12:48:02 INFO - Running main action method: install 12:48:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:48:02 INFO - Reading from file tmpfile_stdout 12:48:02 INFO - Detecting whether we're running mozinstall >=1.0... 12:48:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:48:02 INFO - Reading from file tmpfile_stdout 12:48:02 INFO - Output received: 12:48:02 INFO - Usage: mozinstall [options] installer 12:48:02 INFO - Options: 12:48:02 INFO - -h, --help show this help message and exit 12:48:02 INFO - -d DEST, --destination=DEST 12:48:02 INFO - Directory to install application into. [default: 12:48:02 INFO - "/builds/slave/test"] 12:48:02 INFO - --app=APP Application being installed. [default: firefox] 12:48:02 INFO - mkdir: /builds/slave/test/build/application 12:48:02 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'] 12:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:48:19 INFO - Reading from file tmpfile_stdout 12:48:19 INFO - Output received: 12:48:19 INFO - /builds/slave/test/build/application/firefox/firefox 12:48:19 INFO - Running post-action listener: _resource_record_post_action 12:48:19 INFO - [mozharness: 2017-04-11 19:48:19.457074Z] Finished install step (success) 12:48:19 INFO - [mozharness: 2017-04-11 19:48:19.457347Z] Running run-tests step. 12:48:19 INFO - Running pre-action listener: _resource_record_pre_action 12:48:19 INFO - Running main action method: run_tests 12:48:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:48:19 INFO - Python 2.7.3 12:48:19 INFO - Return code: 0 12:48:19 INFO - grabbing minidump binary from tooltool 12:48:19 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')]} 12:48:19 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 0x1e32290>, '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 0x1e32b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:48:19 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 12:48:19 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 12:48:19 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 12:48:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:48:19 INFO - Return code: 0 12:48:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:48:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:48:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:48:19 INFO - ENV: RUST_BACKTRACE is now 1 12:48:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:48:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:48:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 12:48:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 12:48:19 INFO - Using env: {'DISPLAY': ':0', 12:48:19 INFO - 'HOME': '/home/cltbld', 12:48:19 INFO - 'LANG': 'en_US.UTF-8', 12:48:19 INFO - 'LANGUAGE': 'en_US:en', 12:48:19 INFO - 'LOGNAME': 'cltbld', 12:48:19 INFO - 'MAIL': '/var/mail/cltbld', 12:48:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:48:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:19 INFO - 'MOZ_NO_REMOTE': '1', 12:48:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:48:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:48:19 INFO - 'NO_EM_RESTART': '1', 12:48:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:19 INFO - 'PWD': '/builds/slave/test', 12:48:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:48:19 INFO - 'RUST_BACKTRACE': '1', 12:48:19 INFO - 'SHELL': '/bin/bash', 12:48:19 INFO - 'SHLVL': '1', 12:48:19 INFO - 'TERM': 'linux', 12:48:19 INFO - 'TMOUT': '86400', 12:48:19 INFO - 'USER': 'cltbld', 12:48:19 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339', 12:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:19 INFO - '_': '/tools/buildbot/bin/python'} 12:48:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 12:48:19 INFO - mozversion application_buildid: 20170411185034 12:48:19 INFO - mozversion application_changeset: 2bd14ae23d1842853c1abf500abb6c9613dd3c17 12:48:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:48:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:48:19 INFO - mozversion application_name: Firefox 12:48:19 INFO - mozversion application_remotingname: firefox-dev 12:48:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:48:19 INFO - mozversion application_vendor: Mozilla 12:48:19 INFO - mozversion application_version: 54.0a2 12:48:19 INFO - mozversion platform_buildid: 20170411185034 12:48:19 INFO - mozversion platform_changeset: 2bd14ae23d1842853c1abf500abb6c9613dd3c17 12:48:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:48:19 INFO - mozversion platform_version: 54.0a2 12:48:19 INFO - using testdate: 1491940099 12:48:19 INFO - actual date: 1491940099 12:48:19 INFO - starting webserver on 'localhost:38539' 12:48:19 INFO - SUITE-START | Running 2 tests 12:48:19 INFO - TEST-START | dromaeo_css 12:48:19 INFO - operating with platform_type : linux_ 12:48:19 INFO - Initialising browser for dromaeo_css test... 12:48:19 INFO - TEST-INFO | started process 24247 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0BegOn/profile http://localhost:38539/getInfo.html) 12:48:24 INFO - PROCESS | 24247 | __metrics Screen width/height:1600/1200 12:48:24 INFO - PROCESS | 24247 | colorDepth:24 12:48:24 INFO - PROCESS | 24247 | Browser inner width/height: 1024/697 12:48:24 INFO - PROCESS | 24247 | __metrics 12:48:25 INFO - PROCESS | 24247 | 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" 12:48:25 INFO - PROCESS | 24247 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:48:26 INFO - TEST-INFO | 24247: exit 0 12:48:26 INFO - Browser initialized. 12:48:26 INFO - Running cycle 1/1 for dromaeo_css test... 12:48:26 INFO - TEST-INFO | started process 24341 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0BegOn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:48:38 INFO - PROCESS | 24341 | RSS: Main: 139743232 12:48:38 INFO - PROCESS | 24341 | 12:51:39 INFO - PROCESS | 24341 | Cycle 1(1): loaded http://localhost:38539/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38539/tests/dromaeo/cssquery-ext.html) 12:51:41 INFO - PROCESS | 24341 | RSS: Main: 287154176 12:51:41 INFO - PROCESS | 24341 | 12:53:27 INFO - PROCESS | 24341 | ************************* 12:53:27 INFO - PROCESS | 24341 | A coding exception was thrown and uncaught in a Task. 12:53:27 INFO - PROCESS | 24341 | 12:53:27 ERROR - PROCESS | 24341 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:53:27 INFO - PROCESS | 24341 | Full stack: 12:53:27 INFO - PROCESS | 24341 | ************************* 12:53:27 INFO - PROCESS | 24341 | ************************* 12:53:27 INFO - PROCESS | 24341 | A coding exception was thrown and uncaught in a Task. 12:53:27 INFO - PROCESS | 24341 | 12:53:27 ERROR - PROCESS | 24341 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:53:27 INFO - PROCESS | 24341 | Full stack: 12:53:27 INFO - PROCESS | 24341 | ************************* 12:53:27 INFO - PROCESS | 24341 | ************************* 12:53:27 INFO - PROCESS | 24341 | A coding exception was thrown and uncaught in a Task. 12:53:27 INFO - PROCESS | 24341 | 12:53:27 ERROR - PROCESS | 24341 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:53:27 INFO - PROCESS | 24341 | Full stack: 12:53:27 INFO - PROCESS | 24341 | ************************* 12:53:27 INFO - PROCESS | 24341 | ************************* 12:53:27 INFO - PROCESS | 24341 | A coding exception was thrown and uncaught in a Task. 12:53:27 INFO - PROCESS | 24341 | 12:53:27 ERROR - PROCESS | 24341 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:53:27 INFO - PROCESS | 24341 | Full stack: 12:53:27 INFO - PROCESS | 24341 | ************************* 12:54:43 INFO - PROCESS | 24341 | Cycle 1(1): loaded http://localhost:38539/tests/dromaeo/cssquery-ext.html (next: http://localhost:38539/tests/dromaeo/cssquery-jquery.html) 12:54:43 INFO - PROCESS | 24341 | RSS: Main: 211382272 12:54:43 INFO - PROCESS | 24341 | 12:57:45 INFO - PROCESS | 24341 | Cycle 1(1): loaded http://localhost:38539/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38539/tests/dromaeo/cssquery-mootools.html) 12:57:46 INFO - PROCESS | 24341 | RSS: Main: 171397120 12:57:46 INFO - PROCESS | 24341 | 13:00:48 INFO - PROCESS | 24341 | Cycle 1(1): loaded http://localhost:38539/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38539/tests/dromaeo/cssquery-prototype.html) 13:00:48 INFO - PROCESS | 24341 | RSS: Main: 213041152 13:00:48 INFO - PROCESS | 24341 | 13:03:51 INFO - PROCESS | 24341 | Cycle 1(1): loaded http://localhost:38539/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38539/tests/dromaeo/cssquery-yui.html) 13:03:52 INFO - PROCESS | 24341 | RSS: Main: 177238016 13:03:52 INFO - PROCESS | 24341 | 13:06:55 INFO - PROCESS | 24341 | Cycle 1(1): loaded http://localhost:38539/tests/dromaeo/cssquery-yui.html (next: http://localhost:38539/tests/dromaeo/cssquery-dojo.html) 13:06:55 INFO - PROCESS | 24341 | RSS: Main: 251645952 13:06:55 INFO - PROCESS | 24341 | 13:06:55 INFO - PROCESS | 24341 | __start_tp_report 13:06:55 INFO - PROCESS | 24341 | _x_x_mozilla_page_load 13:06:55 INFO - PROCESS | 24341 | _x_x_mozilla_page_load_details 13:06:55 INFO - PROCESS | 24341 | |i|pagename|runs| 13:06:55 INFO - PROCESS | 24341 | |0;dojo.html;3402.1494626343415;3451.2752322012375;3459.360018396595;3481.0601137692806;3507.9473807892887;5711.942880571193;5765.250517432738;5799.565032622554;5826.563007774413;5826.563007774424;5824.388439213885;5825.417458254183;5835.095560188178;5838.41615838416;5847.210626565412;5153.1239689252825;5172.405173405069;5201.8439446816465;5219.947800521984;5247.134222853225;5054.090263752523;5071.340725705653;5283.392409872856;5285.682859028451;5291.153415453528;5135.666181698196;5152.948470515303;5228.816991405301;5233.528982391584;5245.396779370383;5251.684898906078;5252.842414727565;5253.684778913277;5258.211268309765;5289.391719952217;5022.171341728647;5108.770105345268;5171.637985341028;5229.189475631283;5233.319668443116;5651.180578816049;5682.801101961411;5691.317041954951;5698.6580805151825;5708.914366284509;6125.326214116443;6169.56813023084;6171.290301615327;6177.66022868742;6177.722002509853;6196.473299769525;6247.843803904939;6250.0624906263965;6255.186825712628;6264.874702505925;5624.746886390122;5656.971715141398;5683.175939488792;5721.77112915487;5724.742386592613;6285.842853928689;6286.18279623646;6286.4342209201395;6288.308286088618;6291.307956124822;5988.580799344091;6034;6034.064719968412;6042.244719410074;6048.546359023091;6022.488088512438;6069.939300606938;6103.969480152571;6121.877562448726;6134.662593557308;6150.846228844225;6176.042713379344;6176.042713379434;6191.4118158774845;6191.504679625619;5933.554983376264;5950.018246989285;5950.67271300074;6108.1070335175045;6189.907151392733;4680.297955306732;4763.190257656207;4767.523247675204;4768.237082066853;4795.928061079087;4147.564505726916;4160.750354978711;4180.184863951561;4253.128108737701;4271.380649805761;6011.549133814981;6063.575549711566;6073.757049717962;6088.299845517734;6098.085287206865;6187.969060154761;6228.377162283736;6229.626222426669;6230;6299.370062993756;5814.680192589363;5841;6009.759609615654;6014.699265036731;6039.758409663565;5216.06110900034;5275.287836142072;5294.41761406245;5319.9202011969855;5346.251524786533;6519.185101862267;6545.050967609765;6549.934500654933;6556.8360790980605;6558.803235902835;6005.3694362091355;6203.534734894811;6288.874222515616;6300.464460520907;6301.96849015752;6366.64983425916;6368.42684158428;6380.202474690664;6533.67331633407;6554.770583029571;6138.570300078952;6145.6927153641245;6157.199564056644;6159.630422174684;6164.568480206342;6281.905771413524;6285.717142728497;6286.622802631857;6286.899792536196;6290.937090629035;5773.566982476247;5882.147088672033;6003.45968862787;6006;6106.358832322542;6028.909566356596;6078.179445774941;6086.300075491198;6091.634501929899;6113.877722445437;5846;6101.420365065312;6114.969425152845;6115;6120.908186377119;5959.642421454727;6030.668313242638;6037.45662890342;6041.697915104315;6050.394960503914;6027;6047.304559975483;6050.364711705208;6058.636481811105;6061.242344706911 13:06:55 INFO - PROCESS | 24341 | |1;ext.html;744.9515781474204;832.6544484039124;848.4739461533848;916.1983264643437;929.4098247612764;31001.68998310019;44116.779416102916;45165.77417112914;45167.322490162675;45294;46588.30117548231;46633.30050049252;46716.29925551111;46840.29739553909;46940.53059469404;7901.525908445512;8996.145366190201;9124.041975592567;9130.04134565871;9136.406133601295;7850.136484986676;10662.360258384522;10724.517396717127;10736.73158171048;10811.675649730521;10835.566577336896;10857.51141198644;10858.511366988505;10860.511276992514;10868.565257389695;10772.138228941665;10776.030157285804;10800.78398432027;10805.027547520722;10806.459677016197;10402.79194416115;10436.37381757097;10448.738781530446;10453.738656533571;10460.947695261551;43583.56416435827;43604.345934811005;43739;43792.562074379166;44013.33979990303;4067.369557718529;4114.814833332507;4132.834686612532;4224.753697659198;4254.829806807724;29113.708862911313;29716.85141574278;29894.85052574745;30139.09582712522;30160.396792064254;4281.12236991416;4379.64962802975;4408.779561021936;4412.0955204183065;4516.458025036984;234190;236915.15424228893;237129;237756;238440.99999999825;53708.731456342466;54017.72991135098;54024.45975540194;54079.18881216785;54296.72851635796;54964.17553736697;54976;55123;55209.447905521236;55292.44707552873;16292.103934283728;16696.58258543522;16714.08072555997;16742.916285418494;16757.83242167587;12865.742685146432;13137.408816603276;14758.188299643409;14816.259187040605;14856.554303370916;13086.280254586089;13453.865461345456;13684;13736.175829450265;13871.306434678225;5439.592030597721;5449.945500545023;5545.445455454503;5580.186439407209;5582.190582365534;1033.1373303291834;1035.9430231337199;1038.2368958815264;1046.931949423285;1055.5091882274778;28324.291892702848;28468.43063138767;28645.856770716015;28764.849406023943;29127;1092.8907109289007;1098.1105304703217;1112.7218195451137;1136.5169802833764;1167.0046055534322;15688.607784805243;16695.332186712396;17017.744733829004;17353.264673532547;17877.7318340225;333484;335051;335325;335820;337131;59447.10829337563;59574;59622.40377596255;59759;59838.70080649569;1526.6412393087614;1532.762421824619;1549.349273305192;1552.3945661192145;1553.3398305720025;29713.554296685135;31031.689683103785;31322.843385782926;31586.210344740644;31590;41975.58024419717;42634.5736542643;42671.57328426676;42720;42736.1452770949;287195;288273;290753;290985;291723;28065.579016314365;28756.281092972844;32008.67991320057;33578.49632255469;34671.479927800596;45660.315095272934;45849.31226031679;45880.31179532377;46126;46238.76880615576;33210.50184247286;33622;33679.494807577415;33741;33754.493682594286;33827;34029.31941361109;34056;34318.48522272218;34388.14029649279;39263;39472.40791388074;39537.209255814145;40057;40115.799421003874;32830.83584582062;32998.17504562405;33118;33151.50272745959;33498.162545936546;24870.50258994846;26633.201003969913;26694.13141080106;26761.464770704868;26775.330616733958 13:06:55 INFO - PROCESS | 24341 | |2;jquery.html;471.1754429747941;478.8467690339093;481.1483673897202;483.48267353931266;491.1478584655622;19625.214991400328;21058.789412105874;21155.365339039836;21347;21393.893030534844;21613.67579486305;22256.8887155564;22478.55042899145;22525.436864078405;22788.430289242737;4942.901141977159;6448.45188159007;6642.900356494656;6715.429188518981;6742.696578653972;4249.553796851333;4424.889377765549;6601.834954126137;7088.326608972139;7370.8894366584545;7201.67592458338;7557.357624601916;7565.6217189140325;7571.734989275376;7577.734779282726;7520;7609.315161635424;7615.390768738488;7615.657295421747;7624.809379765523;7243;7446.776596702108;7463.813404664873;7480.102387713455;7483;25220.773916551796;25805.870970645214;26027.73972260272;26080.73919260821;26089;3330.300636866261;3501.6498350165034;3510.3501354363407;3548.716102711777;3608.585012723544;15607.297671604692;15645.217739112999;16064.839351606452;16073.115978621172;16555.834441655672;2132.9228739486616;2180.291405293286;2202.6916231727573;2215.8116560092412;2238.29493709481;108890;111860;112565.43717281443;113011.43494282477;114904.42547787291;17424.91287543554;17744.822551774574;17974.91012544955;17992.370267040853;18281.634367312847;31915.361692766015;32047.839760801045;32085.839570801996;32160;32417.189570260933;10712.892871071346;10802.51388687511;10803.189760767817;10835.891641083488;11007.174461915389;9245.953770231106;9258;9269.304802139866;9450.57472413743;9493.477858717837;8883.511406872554;9262.351635385457;9387.671431499864;9477.66828161025;9519.143277105093;3136.388404261147;3645.3802853514967;3902.6292502212245;3968.861089861841;4052.1287923096675;948.7533241357297;963.5952899782109;971.5822196455592;976.8974257702839;981.1267971505366;19273.132709028127;20487.69268460974;20489.18043278282;20515.17939282442;20757.688634670492;977.4672803322206;978.5449765858767;993.9353941993747;997.2122023601419;1002.734275417015;10762.300450470695;11474.254173478697;11598.88401115995;11617.477213525412;11701.414929253504;680853;689525;691463;740293;746747;113354.43322783333;114917.4254128724;116439.41780291044;117460;120640.3967980172;1244.5581818454318;1257.798752199662;1268.340462959256;1281.448976939906;1282.3524120319366;17439.82560174382;17837.286508539513;18073.36743213981;18290.26838926428;18312.175952082456;49640.503594963586;49925.75037124936;50187.498125019745;50469;50526.24210636917;64013.3598664026;64643.35356646373;65879.67060164668;66021.66989165024;67067.32932670806;12234.816477752664;12370.876291236973;12411.813822792485;12439.813402799145;12642.304673242692;14089.506867259597;14103.506377277157;14159.433622654979;14226.715465690835;14267.857321426653;11673.416329183337;12410.875891240972;12529.436175371948;12551.246925184518;12936.870631293943;10691.5188816505;10806.297590656583;10821.350718956888;10848.294860834336;10871.51078201467;11886.346250955934;12305.56930507428;12323.50705971751;12481.188722732993;12814.249970213228;11818.46816893259;11820.70448238801;12001.1599188056;12123.272603643809;12159.148859579744;11918.52325906954;12530.937345313216;12570;12823;12863.549775757803 13:06:55 INFO - PROCESS | 24341 | |3;mootools.html;1576.3615735627068;1578.1241411016886;1578.1714599835077;1586.5002524204879;1592.068639845691;10501.527431265593;11305.039071678897;11306.038986686142;11307.886921130796;11311.490982905758;11092.168087393458;11336.773264534704;11360.715982100452;11374.601888933888;11389.373584452851;2479.876006199692;2521.6343630173615;2525.078346403568;2525.6590360301307;2525.911593094242;2445.119756887519;2488.14159115105;2519.937001574966;2522.672052633155;2524.911628093017;2458.4714286428416;2481.9751802482015;2507.285423654259;2556.782673472757;2558.2836805694437;2399.904003839853;2440.2557220047966;2448.6327050942414;2449.4856080223176;2455.1897873701637;2342.8594284342994;2360.3391050505716;2363.692719946413;2368.171140100968;2389.2712722619594;11232.719182020433;11303.773924521463;11312.94343528277;11328.263662861887;11334.773304533863;5753.137029445575;5841.970790146064;5885.117232415129;5886.323072846634;5888.558358123158;3529.5235143255845;3597.442396428558;3600.1899572596217;3658.9085272868124;3698.149425632093;1497.139144991634;2274.5678321118935;2321.4312493439215;2352.5177338645535;2380.5357955198742;14528.055676381002;14861.777073343908;16260.59348516273;16696.24689407607;16857;4757.167495688241;4826.565609095198;4918.43438004634;4943.233798761198;4974.37820272466;12763.425645845959;12876.871231287754;12923.28921909304;12930.612081637566;12935.805962910565;6052.303985041688;6067.332593414721;6071.787487437919;6073.119397687311;6076.726547305382;5582.497575218169;5595.4124816894455;5608;5609.943900561024;5685.147227915763;4364.519902810688;4372.169287835365;4457;4511.075229577928;4523;1192.66605350502;1226.4235809169677;1234.5000274888685;1240.6030070377574;1241.4103300932165;968.6028728221395;1013.5185786751382;1020.0615433800921;1023.9334443261164;1032.963846265377;7111.679974401164;7188.892166617505;7199;7205.207427183006;7213.088531539146;990.6037584966072;1022.1260821997085;1039.142707266508;1041.6354276003337;1057.4448414582405;4455.376247325377;4503.07300254837;4566.794494247767;4781.713097214179;4810.2063159578975;13929.651758706113;14543.92728036353;14555.272236388138;14556.126632401878;14594.927025365017;13887.375068121959;14361.138331700131;14787.482438114614;14823.184724840237;14826.777598336033;8843.646254149762;10590.788184236271;10774.138068954622;10790.460476976119;10831.241813073168;6068.575199735976;7094.751683691253;7162.2121566628675;7282.453815963718;7515.812104697207;11268.7746245073;11314.208005439541;11365;11371.090312774793;11575.42122893835;12220.51117955272;12291.9385403076;12344.321062341658;12349.93825030869;12350.135490515577;9654.27592930519;9817.460039697886;9857.704268871945;9906.157976572073;9906.900930990887;10658.520366583678;10707.839382409426;10739.087177589992;10760.784784304427;10917.39954302521;9227.400218986013;9298.395604285699;9440.811183776148;9628.47043412591;9667.274954378308;8671.609777560152;8821.117888211127;8927.642894284156;8931.73204803857;9214.493202873638;8161.877571836546;8196.672133114847;8207;8217.630206640835;8220.958895205486;5800.9419905800405;5873.471387575139;5942.554308426799;5947.464728174485;6063.605865618721;5808.564357673107;5884.293884733859;5911.615744976562;5961.493273071664;6073.271207455134 13:06:55 INFO - PROCESS | 24341 | |4;prototype.html;415.8752374287713;418.9895252618686;438.563629188957;444.9955500444995;451.42668810610525;5068.0370729561355;5126.487351264872;5146.81986130486;5172.534471897528;5211;5000.549950504454;5038.9748051259785;5058.570021548173;5082.941408729461;5143.974280128604;4285.142971405716;4288.976271728424;4298;4303.35449682547;4347.152305300468;4322;4326.783660816962;4331.43691320128;4346.608805207531;4348.91302173958;4178.847893334935;4280.5291417944;4296.625079974409;4297.355396690506;4299.763513006784;4261.76560289184;4267.738098807637;4272.978635106821;4285.442892423965;4292.420523229371;4031.395290706388;4053.635172834459;4055.3916912463073;4055.472788537501;4091.115763386805;4818.469968303484;5063.949360506384;5066.392032956032;5091.745412729349;5095.248565774477;4989.727619457015;4994.750262486898;5014.974925125388;5074.822381216677;5082.593392528589;5381.946180538183;5397.919031214535;5402.459754024605;5426.403095659474;5437.864053398657;4364.2798938175165;4444.73331600105;4475.910481790346;4498.685092043558;4507.977460112711;5329.6002799789785;5415.268938693265;5508.641938274;5593.272874526327;5600.663960162363;4579.9084018319445;4590;4596.3105534170145;4596.908061838744;4609.285560738091;4527.569880861313;4596.678232523691;4657.138429390574;4660.83063516805;4692.6245900327895;4935.568685081358;4978.651494395358;4983.451820299764;4992.550670439678;5016.372953380827;5326.414094449608;5351.839444816662;5355.598330125256;5355.678659280456;5371.785128594813;1542.7608720648318;1569.7331453652907;1580.5495433801311;1592.6894255620043;1621.27853105367;1202.0023380594262;1227.5396726227664;1233.975320493603;1245.651217659057;1257.2456526084459;5226.320578324793;5253.605979551549;5297.364316282071;5392.191171324254;5394.487523685244;5423.484768946943;5522.66863988162;5545.223668686387;5569.19246709233;5629.802956896489;5073.847784566469;5368.275282836845;5382.811601593926;5399;5409.486098820606;2921.9707802922126;2922.6346706661666;2926.9121926342245;2929.7363237308746;2937.7796665250194;5600.355959064678;5709.457601527848;5715.485606295453;5785.768569257267;5795.420457954255;5564.8330550083565;5577.414371491016;5603.971980140154;5616.438356164433;5654.897295027512;5735.340435849931;5746.224259724883;5748.597598168171;5763.250777398911;5764.550066221923;5285.418603953485;5402.486763757437;5486.506214440719;5502.944970550403;5507.972460137674;5247.294629245587;5325.920111198413;5385.757640906247;5435.211894275387;5465.699386533779;5737.454941780524;5747.8563035924435;5764.567657425626;5786.4213578643485;5815.534757219328;4492.685512014127;4626.236670949255;4640;4644.9767751161025;4651.534846515321;5443.530246833254;5459.672419654833;5499.340079190522;5571.387147413862;5588.469095435927;5348.897639369079;5359.311816777703;5396.9460305398015;5410.675359478287;5442.619016668795;5197.792088316576;5386.919196212139;5392.946070539245;5407.970587353799;5410.998965191452;5422.674639521641;5440.646357986719;5445.7549410277425;5452.509274165344;5459.645123066988;5424.945750542602;5447.645903016291;5455.536279416294;5472.72636368188;5511.834644960658;5113.667611605234;5307.761150748149;5319.547838433777;5326.414094449685;5358.6248962572245 13:06:55 INFO - PROCESS | 24341 | |5;yui.html;802.4571516753533;864.9783755406111;897.6544676451729;905.9773505662358;924.8011677489343;16123.677526449464;16286.511404657864;16327.51017469475;16338.264778084986;16651.66696666066;15631.218439078057;15695;15741.763873541906;15750.212489375543;16692.74960875588;849.2102344819331;851.6933903794629;863.8142799298149;864.4899509289518;865.139186509424;864.5201912938317;876.4609764994547;877.4691311756392;877.4779006491127;877.7322916510461;908.0329449136682;908.7864351877304;909.5043201455189;909.9408538445012;910.1899309614448;907.6732376344511;907.686848037426;908.4231512989234;908.6183802802806;908.6320040383622;665.5341261117189;666.7999600119945;666.986660266792;667.2393471442559;667.6461475418037;16733.497995060166;16987.660246794996;17138.14309284531;17696.557586060324;17909.820901791078;13938.233397163152;14032.158070515801;14160.645983850383;14172.929135354258;14529.491467798682;13826.516071937434;13875.37560809766;14026.85973140276;14152;14209.644758881112;3933.468981687493;3945.506811648544;3969.821358038895;3972.145988612462;4095.610916962884;51143.74428127873;51893.22160167601;55499;73526.63236683836;93330;10731.248812583044;11158.1631377647;11289.604863829727;11412.771744565229;11538;19787.20851165966;19977.700334494995;20092;20188.293409730588;20282.59434811325;8410.705625303208;8450.239478446969;8597.18326758957;8735.213830755263;8854.64581416749;4330.090680957034;4401.581849724271;4426.136903303889;4434.512203657595;4436.112777444524;3851.537815462106;3958.2281455116554;4077.55146933843;4135.255653982312;4142.420061191376;686.8282929267683;687.700850130195;688.7348370877248;713.8215446138465;732.3189433826592;785.8938046036676;786.4573444323398;789.6683392974965;792.1998781230998;816.9632366543519;11041.944790275997;11178.329300242012;11380.203385762918;11408.144389170846;11462.197646164688;676.1413005483009;678.4504551313452;692.069166970424;692.1866806502339;694.0595493106869;4680.274557443602;4816.301636262722;4820.975895120502;4846.006568653417;4850.345203397566;81841.18158818455;81977.59011205025;82246;83881;84491;43701.78149109234;49125.50874491209;59771.70114149489;76376.23623763802;78211;59794;60971;60984;61057.69471152616;61233;11133.443327833738;11560;11646.41767911584;11863.110266729856;11901;41430.37854432246;42911;42975;43373.56626433822;43733.34399984066;49262.75368623134;51097;51616.22575661443;51992.220116699034;55935;12914.80627790565;13145.277009764555;13468.86531134676;13802.447902083804;14128.576142715736;14977.550673479813;16428.42500512476;16433.424830130887;17300.22149003323;17390.47828565145;15406.998545094533;15728.528144155694;16359.427420040241;16568.668626627157;16583.585410364838;14300.427982880568;14387.640308992359;14484.710305793615;14645.70708585801;15378.154201519024;13455.730885382432;13875.86124138786;13965.790513142514;14384.712305754036;15093.24533773285;9239.676611318571;9712.969117816026;9793.412395256308;9844.114029737359;10151.949240254047;12310.876891230973;12769.319446653004;12803;12812.423440945366;12910.612681619567 13:06:55 INFO - PROCESS | 24341 | __end_tp_report 13:06:55 INFO - PROCESS | 24341 | __start_cc_report 13:06:55 INFO - PROCESS | 24341 | _x_x_mozilla_cycle_collect,1459 13:06:55 INFO - PROCESS | 24341 | __end_cc_report 13:06:55 INFO - PROCESS | 24341 | __startTimestamp1491941215390__endTimestamp 13:06:55 INFO - PROCESS | 24341 | 13:06:55 INFO - PROCESS | 24341 | ------- Summary: start ------- 13:06:55 INFO - PROCESS | 24341 | Number of tests: 6 13:06:55 INFO - PROCESS | 24341 | 13:06:55 INFO - PROCESS | 24341 | [#0] dojo.html Cycles:165 Average:5751.04 Median:6027.00 stddev:657.76 (10.9%) stddev-sans-first:633.45 13:06:55 INFO - PROCESS | 24341 | Values: 3402.1 3451.3 3459.4 3481.1 3507.9 5711.9 5765.3 5799.6 5826.6 5826.6 5824.4 5825.4 5835.1 5838.4 5847.2 5153.1 5172.4 5201.8 5219.9 5247.1 5054.1 5071.3 5283.4 5285.7 5291.2 5135.7 5152.9 5228.8 5233.5 5245.4 5251.7 5252.8 5253.7 5258.2 5289.4 5022.2 5108.8 5171.6 5229.2 5233.3 5651.2 5682.8 5691.3 5698.7 5708.9 6125.3 6169.6 6171.3 6177.7 6177.7 6196.5 6247.8 6250.1 6255.2 6264.9 5624.7 5657.0 5683.2 5721.8 5724.7 6285.8 6286.2 6286.4 6288.3 6291.3 5988.6 6034.0 6034.1 6042.2 6048.5 6022.5 6069.9 6104.0 6121.9 6134.7 6150.8 6176.0 6176.0 6191.4 6191.5 5933.6 5950.0 5950.7 6108.1 6189.9 4680.3 4763.2 4767.5 4768.2 4795.9 4147.6 4160.8 4180.2 4253.1 4271.4 6011.5 6063.6 6073.8 6088.3 6098.1 6188.0 6228.4 6229.6 6230.0 6299.4 5814.7 5841.0 6009.8 6014.7 6039.8 5216.1 5275.3 5294.4 5319.9 5346.3 6519.2 6545.1 6549.9 6556.8 6558.8 6005.4 6203.5 6288.9 6300.5 6302.0 6366.6 6368.4 6380.2 6533.7 6554.8 6138.6 6145.7 6157.2 6159.6 6164.6 6281.9 6285.7 6286.6 6286.9 6290.9 5773.6 5882.1 6003.5 6006.0 6106.4 6028.9 6078.2 6086.3 6091.6 6113.9 5846.0 6101.4 6115.0 6115.0 6120.9 5959.6 6030.7 6037.5 6041.7 6050.4 6027.0 6047.3 6050.4 6058.6 6061.2 13:06:55 INFO - PROCESS | 24341 | 13:06:55 INFO - PROCESS | 24341 | [#1] ext.html Cycles:180 Average:46409.39 Median:33134.75 stddev:75717.34 (228.5%) stddev-sans-first:75852.12 13:06:55 INFO - PROCESS | 24341 | Values: 745.0 832.7 848.5 916.2 929.4 31001.7 44116.8 45165.8 45167.3 45294.0 46588.3 46633.3 46716.3 46840.3 46940.5 7901.5 8996.1 9124.0 9130.0 9136.4 7850.1 10662.4 10724.5 10736.7 10811.7 10835.6 10857.5 10858.5 10860.5 10868.6 10772.1 10776.0 10800.8 10805.0 10806.5 10402.8 10436.4 10448.7 10453.7 10460.9 43583.6 43604.3 43739.0 43792.6 44013.3 4067.4 4114.8 4132.8 4224.8 4254.8 29113.7 29716.9 29894.9 30139.1 30160.4 4281.1 4379.6 4408.8 4412.1 4516.5 234190.0 236915.2 237129.0 237756.0 238441.0 53708.7 54017.7 54024.5 54079.2 54296.7 54964.2 54976.0 55123.0 55209.4 55292.4 16292.1 16696.6 16714.1 16742.9 16757.8 12865.7 13137.4 14758.2 14816.3 14856.6 13086.3 13453.9 13684.0 13736.2 13871.3 5439.6 5449.9 5545.4 5580.2 5582.2 1033.1 1035.9 1038.2 1046.9 1055.5 28324.3 28468.4 28645.9 28764.8 29127.0 1092.9 1098.1 1112.7 1136.5 1167.0 15688.6 16695.3 17017.7 17353.3 17877.7 333484.0 335051.0 335325.0 335820.0 337131.0 59447.1 59574.0 59622.4 59759.0 59838.7 1526.6 1532.8 1549.3 1552.4 1553.3 29713.6 31031.7 31322.8 31586.2 31590.0 41975.6 42634.6 42671.6 42720.0 42736.1 287195.0 288273.0 290753.0 290985.0 291723.0 28065.6 28756.3 32008.7 33578.5 34671.5 45660.3 45849.3 45880.3 46126.0 46238.8 33210.5 33622.0 33679.5 33741.0 33754.5 33827.0 34029.3 34056.0 34318.5 34388.1 39263.0 39472.4 39537.2 40057.0 40115.8 32830.8 32998.2 33118.0 33151.5 33498.2 24870.5 26633.2 26694.1 26761.5 26775.3 13:06:55 INFO - PROCESS | 24341 | 13:06:55 INFO - PROCESS | 24341 | [#2] jquery.html Cycles:180 Average:38976.86 Median:12236.35 stddev:116825.51 (954.7%) stddev-sans-first:117117.46 13:06:55 INFO - PROCESS | 24341 | Values: 471.2 478.8 481.1 483.5 491.1 19625.2 21058.8 21155.4 21347.0 21393.9 21613.7 22256.9 22478.6 22525.4 22788.4 4942.9 6448.5 6642.9 6715.4 6742.7 4249.6 4424.9 6601.8 7088.3 7370.9 7201.7 7557.4 7565.6 7571.7 7577.7 7520.0 7609.3 7615.4 7615.7 7624.8 7243.0 7446.8 7463.8 7480.1 7483.0 25220.8 25805.9 26027.7 26080.7 26089.0 3330.3 3501.6 3510.4 3548.7 3608.6 15607.3 15645.2 16064.8 16073.1 16555.8 2132.9 2180.3 2202.7 2215.8 2238.3 108890.0 111860.0 112565.4 113011.4 114904.4 17424.9 17744.8 17974.9 17992.4 18281.6 31915.4 32047.8 32085.8 32160.0 32417.2 10712.9 10802.5 10803.2 10835.9 11007.2 9246.0 9258.0 9269.3 9450.6 9493.5 8883.5 9262.4 9387.7 9477.7 9519.1 3136.4 3645.4 3902.6 3968.9 4052.1 948.8 963.6 971.6 976.9 981.1 19273.1 20487.7 20489.2 20515.2 20757.7 977.5 978.5 993.9 997.2 1002.7 10762.3 11474.3 11598.9 11617.5 11701.4 680853.0 689525.0 691463.0 740293.0 746747.0 113354.4 114917.4 116439.4 117460.0 120640.4 1244.6 1257.8 1268.3 1281.4 1282.4 17439.8 17837.3 18073.4 18290.3 18312.2 49640.5 49925.8 50187.5 50469.0 50526.2 64013.4 64643.4 65879.7 66021.7 67067.3 12234.8 12370.9 12411.8 12439.8 12642.3 14089.5 14103.5 14159.4 14226.7 14267.9 11673.4 12410.9 12529.4 12551.2 12936.9 10691.5 10806.3 10821.4 10848.3 10871.5 11886.3 12305.6 12323.5 12481.2 12814.2 11818.5 11820.7 12001.2 12123.3 12159.1 11918.5 12530.9 12570.0 12823.0 12863.5 13:06:55 INFO - PROCESS | 24341 | 13:06:55 INFO - PROCESS | 24341 | [#3] mootools.html Cycles:180 Average:7032.87 Median:2485.06 stddev:4320.67 (173.9%) stddev-sans-first:4313.34 13:06:55 INFO - PROCESS | 24341 | Values: 1576.4 1578.1 1578.2 1586.5 1592.1 10501.5 11305.0 11306.0 11307.9 11311.5 11092.2 11336.8 11360.7 11374.6 11389.4 2479.9 2521.6 2525.1 2525.7 2525.9 2445.1 2488.1 2519.9 2522.7 2524.9 2458.5 2482.0 2507.3 2556.8 2558.3 2399.9 2440.3 2448.6 2449.5 2455.2 2342.9 2360.3 2363.7 2368.2 2389.3 11232.7 11303.8 11312.9 11328.3 11334.8 5753.1 5842.0 5885.1 5886.3 5888.6 3529.5 3597.4 3600.2 3658.9 3698.1 1497.1 2274.6 2321.4 2352.5 2380.5 14528.1 14861.8 16260.6 16696.2 16857.0 4757.2 4826.6 4918.4 4943.2 4974.4 12763.4 12876.9 12923.3 12930.6 12935.8 6052.3 6067.3 6071.8 6073.1 6076.7 5582.5 5595.4 5608.0 5609.9 5685.1 4364.5 4372.2 4457.0 4511.1 4523.0 1192.7 1226.4 1234.5 1240.6 1241.4 968.6 1013.5 1020.1 1023.9 1033.0 7111.7 7188.9 7199.0 7205.2 7213.1 990.6 1022.1 1039.1 1041.6 1057.4 4455.4 4503.1 4566.8 4781.7 4810.2 13929.7 14543.9 14555.3 14556.1 14594.9 13887.4 14361.1 14787.5 14823.2 14826.8 8843.6 10590.8 10774.1 10790.5 10831.2 6068.6 7094.8 7162.2 7282.5 7515.8 11268.8 11314.2 11365.0 11371.1 11575.4 12220.5 12291.9 12344.3 12349.9 12350.1 9654.3 9817.5 9857.7 9906.2 9906.9 10658.5 10707.8 10739.1 10760.8 10917.4 9227.4 9298.4 9440.8 9628.5 9667.3 8671.6 8821.1 8927.6 8931.7 9214.5 8161.9 8196.7 8207.0 8217.6 8221.0 5800.9 5873.5 5942.6 5947.5 6063.6 5808.6 5884.3 5911.6 5961.5 6073.3 13:06:55 INFO - PROCESS | 24341 | 13:06:55 INFO - PROCESS | 24341 | [#4] prototype.html Cycles:180 Average:4708.36 Median:5185.16 stddev:1252.33 (24.2%) stddev-sans-first:1213.69 13:06:55 INFO - PROCESS | 24341 | Values: 415.9 419.0 438.6 445.0 451.4 5068.0 5126.5 5146.8 5172.5 5211.0 5000.5 5039.0 5058.6 5082.9 5144.0 4285.1 4289.0 4298.0 4303.4 4347.2 4322.0 4326.8 4331.4 4346.6 4348.9 4178.8 4280.5 4296.6 4297.4 4299.8 4261.8 4267.7 4273.0 4285.4 4292.4 4031.4 4053.6 4055.4 4055.5 4091.1 4818.5 5063.9 5066.4 5091.7 5095.2 4989.7 4994.8 5015.0 5074.8 5082.6 5381.9 5397.9 5402.5 5426.4 5437.9 4364.3 4444.7 4475.9 4498.7 4508.0 5329.6 5415.3 5508.6 5593.3 5600.7 4579.9 4590.0 4596.3 4596.9 4609.3 4527.6 4596.7 4657.1 4660.8 4692.6 4935.6 4978.7 4983.5 4992.6 5016.4 5326.4 5351.8 5355.6 5355.7 5371.8 1542.8 1569.7 1580.5 1592.7 1621.3 1202.0 1227.5 1234.0 1245.7 1257.2 5226.3 5253.6 5297.4 5392.2 5394.5 5423.5 5522.7 5545.2 5569.2 5629.8 5073.8 5368.3 5382.8 5399.0 5409.5 2922.0 2922.6 2926.9 2929.7 2937.8 5600.4 5709.5 5715.5 5785.8 5795.4 5564.8 5577.4 5604.0 5616.4 5654.9 5735.3 5746.2 5748.6 5763.3 5764.6 5285.4 5402.5 5486.5 5502.9 5508.0 5247.3 5325.9 5385.8 5435.2 5465.7 5737.5 5747.9 5764.6 5786.4 5815.5 4492.7 4626.2 4640.0 4645.0 4651.5 5443.5 5459.7 5499.3 5571.4 5588.5 5348.9 5359.3 5396.9 5410.7 5442.6 5197.8 5386.9 5392.9 5408.0 5411.0 5422.7 5440.6 5445.8 5452.5 5459.6 5424.9 5447.6 5455.5 5472.7 5511.8 5113.7 5307.8 5319.5 5326.4 5358.6 13:06:55 INFO - PROCESS | 24341 | 13:06:55 INFO - PROCESS | 24341 | [#5] yui.html Cycles:180 Average:17430.95 Median:43537.67 stddev:21271.64 (48.9%) stddev-sans-first:21294.66 13:06:55 INFO - PROCESS | 24341 | Values: 802.5 865.0 897.7 906.0 924.8 16123.7 16286.5 16327.5 16338.3 16651.7 15631.2 15695.0 15741.8 15750.2 16692.7 849.2 851.7 863.8 864.5 865.1 864.5 876.5 877.5 877.5 877.7 908.0 908.8 909.5 909.9 910.2 907.7 907.7 908.4 908.6 908.6 665.5 666.8 667.0 667.2 667.6 16733.5 16987.7 17138.1 17696.6 17909.8 13938.2 14032.2 14160.6 14172.9 14529.5 13826.5 13875.4 14026.9 14152.0 14209.6 3933.5 3945.5 3969.8 3972.1 4095.6 51143.7 51893.2 55499.0 73526.6 93330.0 10731.2 11158.2 11289.6 11412.8 11538.0 19787.2 19977.7 20092.0 20188.3 20282.6 8410.7 8450.2 8597.2 8735.2 8854.6 4330.1 4401.6 4426.1 4434.5 4436.1 3851.5 3958.2 4077.6 4135.3 4142.4 686.8 687.7 688.7 713.8 732.3 785.9 786.5 789.7 792.2 817.0 11041.9 11178.3 11380.2 11408.1 11462.2 676.1 678.5 692.1 692.2 694.1 4680.3 4816.3 4821.0 4846.0 4850.3 81841.2 81977.6 82246.0 83881.0 84491.0 43701.8 49125.5 59771.7 76376.2 78211.0 59794.0 60971.0 60984.0 61057.7 61233.0 11133.4 11560.0 11646.4 11863.1 11901.0 41430.4 42911.0 42975.0 43373.6 43733.3 49262.8 51097.0 51616.2 51992.2 55935.0 12914.8 13145.3 13468.9 13802.4 14128.6 14977.6 16428.4 16433.4 17300.2 17390.5 15407.0 15728.5 16359.4 16568.7 16583.6 14300.4 14387.6 14484.7 14645.7 15378.2 13455.7 13875.9 13965.8 14384.7 15093.2 9239.7 9713.0 9793.4 9844.1 10151.9 12310.9 12769.3 12803.0 12812.4 12910.6 13:06:55 INFO - PROCESS | 24341 | -------- Summary: end -------- 13:06:55 INFO - PROCESS | 24341 | 13:06:56 INFO - TEST-INFO | 24341: exit 0 13:06:56 INFO - TEST-OK | dromaeo_css | took 1116503ms 13:06:56 INFO - TEST-START | kraken 13:06:56 INFO - operating with platform_type : linux_ 13:06:56 INFO - Initialising browser for kraken test... 13:06:56 INFO - TEST-INFO | started process 24660 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1uhdzm/profile http://localhost:38539/getInfo.html) 13:07:00 INFO - PROCESS | 24660 | __metrics Screen width/height:1600/1200 13:07:00 INFO - PROCESS | 24660 | colorDepth:24 13:07:00 INFO - PROCESS | 24660 | Browser inner width/height: 1024/697 13:07:00 INFO - PROCESS | 24660 | __metrics 13:07:01 INFO - PROCESS | 24660 | 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" 13:07:01 INFO - PROCESS | 24660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:07:02 INFO - TEST-INFO | 24660: exit 0 13:07:02 INFO - Browser initialized. 13:07:02 INFO - Running cycle 1/1 for kraken test... 13:07:02 INFO - TEST-INFO | started process 24751 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1uhdzm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:07:14 INFO - PROCESS | 24751 | RSS: Main: 140312576 13:07:14 INFO - PROCESS | 24751 | 13:07:16 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38539/tests/kraken/driver.html?testName=audio-beat-detection) 13:07:17 INFO - PROCESS | 24751 | RSS: Main: 183283712 13:07:17 INFO - PROCESS | 24751 | 13:07:20 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38539/tests/kraken/driver.html?testName=audio-dft) 13:07:20 INFO - PROCESS | 24751 | RSS: Main: 174317568 13:07:20 INFO - PROCESS | 24751 | 13:07:23 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38539/tests/kraken/driver.html?testName=audio-fft) 13:07:24 INFO - PROCESS | 24751 | RSS: Main: 265617408 13:07:24 INFO - PROCESS | 24751 | 13:07:26 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38539/tests/kraken/driver.html?testName=audio-oscillator) 13:07:26 INFO - PROCESS | 24751 | RSS: Main: 172113920 13:07:26 INFO - PROCESS | 24751 | 13:07:29 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38539/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:07:29 INFO - PROCESS | 24751 | RSS: Main: 174800896 13:07:29 INFO - PROCESS | 24751 | 13:07:32 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38539/tests/kraken/driver.html?testName=imaging-darkroom) 13:07:33 INFO - PROCESS | 24751 | RSS: Main: 213405696 13:07:33 INFO - PROCESS | 24751 | 13:07:36 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38539/tests/kraken/driver.html?testName=imaging-desaturate) 13:07:37 INFO - PROCESS | 24751 | RSS: Main: 212279296 13:07:37 INFO - PROCESS | 24751 | 13:07:39 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38539/tests/kraken/driver.html?testName=json-parse-financial) 13:07:40 INFO - PROCESS | 24751 | RSS: Main: 210264064 13:07:40 INFO - PROCESS | 24751 | 13:07:41 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38539/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:07:42 INFO - PROCESS | 24751 | RSS: Main: 172613632 13:07:42 INFO - PROCESS | 24751 | 13:07:44 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38539/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:07:44 INFO - PROCESS | 24751 | RSS: Main: 179503104 13:07:44 INFO - PROCESS | 24751 | 13:07:46 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38539/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:07:47 INFO - PROCESS | 24751 | RSS: Main: 188854272 13:07:47 INFO - PROCESS | 24751 | 13:07:50 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38539/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:07:50 INFO - PROCESS | 24751 | RSS: Main: 197955584 13:07:50 INFO - PROCESS | 24751 | 13:07:53 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38539/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:07:54 INFO - PROCESS | 24751 | RSS: Main: 194818048 13:07:54 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | Cycle 1(1): loaded http://localhost:38539/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38539/tests/kraken/driver.html?testName=ai-astar) 13:07:55 INFO - PROCESS | 24751 | RSS: Main: 244989952 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | __start_tp_report 13:07:55 INFO - PROCESS | 24751 | _x_x_mozilla_page_load 13:07:55 INFO - PROCESS | 24751 | _x_x_mozilla_page_load_details 13:07:55 INFO - PROCESS | 24751 | |i|pagename|runs| 13:07:55 INFO - PROCESS | 24751 | |0;ai-astar;104;113;113;113;120;113;112;113;112;112 13:07:55 INFO - PROCESS | 24751 | |1;audio-beat-detection;144;115;121;151;115;117;115;119;134;116 13:07:55 INFO - PROCESS | 24751 | |2;audio-dft;161;162;161;161;161;163;163;162;161;161 13:07:55 INFO - PROCESS | 24751 | |3;audio-fft;75;75;76;109;76;76;76;76;89;74 13:07:55 INFO - PROCESS | 24751 | |4;audio-oscillator;83;83;113;82;82;83;105;82;83;105 13:07:55 INFO - PROCESS | 24751 | |5;imaging-gaussian-blur;113;113;113;112;113;113;113;113;113;113 13:07:55 INFO - PROCESS | 24751 | |6;imaging-darkroom;147;146;147;146;146;147;146;146;146;146 13:07:55 INFO - PROCESS | 24751 | |7;imaging-desaturate;95;94;94;94;94;93;94;94;94;93 13:07:55 INFO - PROCESS | 24751 | |8;json-parse-financial;55;55;56;55;55;56;56;55;55;55 13:07:55 INFO - PROCESS | 24751 | |9;json-stringify-tinderbox;54;54;53;53;54;54;54;54;54;54 13:07:55 INFO - PROCESS | 24751 | |10;stanford-crypto-aes;77;76;80;79;84;83;76;84;78;80 13:07:55 INFO - PROCESS | 24751 | |11;stanford-crypto-ccm;132;124;123;131;123;125;119;121;160;121 13:07:55 INFO - PROCESS | 24751 | |12;stanford-crypto-pbkdf2;160;161;163;162;173;163;158;159;165;165 13:07:55 INFO - PROCESS | 24751 | |13;stanford-crypto-sha256-iterative;61;61;62;61;61;62;63;63;59;58 13:07:55 INFO - PROCESS | 24751 | __end_tp_report 13:07:55 INFO - PROCESS | 24751 | __start_cc_report 13:07:55 INFO - PROCESS | 24751 | _x_x_mozilla_cycle_collect,1642 13:07:55 INFO - PROCESS | 24751 | __end_cc_report 13:07:55 INFO - PROCESS | 24751 | __startTimestamp1491941275927__endTimestamp 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | ------- Summary: start ------- 13:07:55 INFO - PROCESS | 24751 | Number of tests: 14 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:3.81 (3.4%) stddev-sans-first:2.51 13:07:55 INFO - PROCESS | 24751 | Values: 104.0 113.0 113.0 113.0 120.0 113.0 112.0 113.0 112.0 112.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:120.00 stddev:13.39 (11.2%) stddev-sans-first:12.25 13:07:55 INFO - PROCESS | 24751 | Values: 144.0 115.0 121.0 151.0 115.0 117.0 115.0 119.0 134.0 116.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#2] audio-dft Cycles:10 Average:161.60 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 13:07:55 INFO - PROCESS | 24751 | Values: 161.0 162.0 161.0 161.0 161.0 163.0 163.0 162.0 161.0 161.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#3] audio-fft Cycles:10 Average:80.20 Median:76.00 stddev:10.99 (14.5%) stddev-sans-first:11.50 13:07:55 INFO - PROCESS | 24751 | Values: 75.0 75.0 76.0 109.0 76.0 76.0 76.0 76.0 89.0 74.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:82.50 stddev:12.32 (14.9%) stddev-sans-first:12.80 13:07:55 INFO - PROCESS | 24751 | Values: 83.0 83.0 113.0 82.0 82.0 83.0 105.0 82.0 83.0 105.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 13:07:55 INFO - PROCESS | 24751 | Values: 113.0 113.0 113.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 13:07:55 INFO - PROCESS | 24751 | Values: 147.0 146.0 147.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 13:07:55 INFO - PROCESS | 24751 | Values: 95.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 93.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 13:07:55 INFO - PROCESS | 24751 | Values: 55.0 55.0 56.0 55.0 55.0 56.0 56.0 55.0 55.0 55.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#9] json-stringify-tinderbox Cycles:10 Average:53.80 Median:54.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 13:07:55 INFO - PROCESS | 24751 | Values: 54.0 54.0 53.0 53.0 54.0 54.0 54.0 54.0 54.0 54.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#10] stanford-crypto-aes Cycles:10 Average:79.70 Median:80.00 stddev:3.09 (3.9%) stddev-sans-first:3.12 13:07:55 INFO - PROCESS | 24751 | Values: 77.0 76.0 80.0 79.0 84.0 83.0 76.0 84.0 78.0 80.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#11] stanford-crypto-ccm Cycles:10 Average:127.90 Median:124.50 stddev:12.03 (9.7%) stddev-sans-first:12.67 13:07:55 INFO - PROCESS | 24751 | Values: 132.0 124.0 123.0 131.0 123.0 125.0 119.0 121.0 160.0 121.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.90 Median:163.00 stddev:4.25 (2.6%) stddev-sans-first:4.38 13:07:55 INFO - PROCESS | 24751 | Values: 160.0 161.0 163.0 162.0 173.0 163.0 158.0 159.0 165.0 165.0 13:07:55 INFO - PROCESS | 24751 | 13:07:55 INFO - PROCESS | 24751 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.50 stddev:1.60 (2.6%) stddev-sans-first:1.69 13:07:55 INFO - PROCESS | 24751 | Values: 61.0 61.0 62.0 61.0 61.0 62.0 63.0 63.0 59.0 58.0 13:07:55 INFO - PROCESS | 24751 | -------- Summary: end -------- 13:07:55 INFO - PROCESS | 24751 | 13:07:56 INFO - TEST-INFO | 24751: exit 0 13:07:57 INFO - TEST-OK | kraken | took 60783ms 13:07:57 INFO - SUITE-END | took 1177s 13:07:57 INFO - Completed test suite (00:19:37) 13:07:57 INFO - javascript benchmark 13:07:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7817.718925238392, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3402.1494626343415, 3451.2752322012375, 3459.360018396595, 3481.0601137692806, 3507.9473807892887, 5711.942880571193, 5765.250517432738, 5799.565032622554, 5826.563007774413, 5826.563007774424, 5824.388439213885, 5825.417458254183, 5835.095560188178, 5838.41615838416, 5847.210626565412, 5153.1239689252825, 5172.405173405069, 5201.8439446816465, 5219.947800521984, 5247.134222853225, 5054.090263752523, 5071.340725705653, 5283.392409872856, 5285.682859028451, 5291.153415453528, 5135.666181698196, 5152.948470515303, 5228.816991405301, 5233.528982391584, 5245.396779370383, 5251.684898906078, 5252.842414727565, 5253.684778913277, 5258.211268309765, 5289.391719952217, 5022.171341728647, 5108.770105345268, 5171.637985341028, 5229.189475631283, 5233.319668443116, 5651.180578816049, 5682.801101961411, 5691.317041954951, 5698.6580805151825, 5708.914366284509, 6125.326214116443, 6169.56813023084, 6171.290301615327, 6177.66022868742, 6177.722002509853, 6196.473299769525, 6247.843803904939, 6250.0624906263965, 6255.186825712628, 6264.874702505925, 5624.746886390122, 5656.971715141398, 5683.175939488792, 5721.77112915487, 5724.742386592613, 6285.842853928689, 6286.18279623646, 6286.4342209201395, 6288.308286088618, 6291.307956124822, 5988.580799344091, 6034.0, 6034.064719968412, 6042.244719410074, 6048.546359023091, 6022.488088512438, 6069.939300606938, 6103.969480152571, 6121.877562448726, 6134.662593557308, 6150.846228844225, 6176.042713379344, 6176.042713379434, 6191.4118158774845, 6191.504679625619, 5933.554983376264, 5950.018246989285, 5950.67271300074, 6108.1070335175045, 6189.907151392733, 4680.297955306732, 4763.190257656207, 4767.523247675204, 4768.237082066853, 4795.928061079087, 4147.564505726916, 4160.750354978711, 4180.184863951561, 4253.128108737701, 4271.380649805761, 6011.549133814981, 6063.575549711566, 6073.757049717962, 6088.299845517734, 6098.085287206865, 6187.969060154761, 6228.377162283736, 6229.626222426669, 6230.0, 6299.370062993756, 5814.680192589363, 5841.0, 6009.759609615654, 6014.699265036731, 6039.758409663565, 5216.06110900034, 5275.287836142072, 5294.41761406245, 5319.9202011969855, 5346.251524786533, 6519.185101862267, 6545.050967609765, 6549.934500654933, 6556.8360790980605, 6558.803235902835, 6005.3694362091355, 6203.534734894811, 6288.874222515616, 6300.464460520907, 6301.96849015752, 6366.64983425916, 6368.42684158428, 6380.202474690664, 6533.67331633407, 6554.770583029571, 6138.570300078952, 6145.6927153641245, 6157.199564056644, 6159.630422174684, 6164.568480206342, 6281.905771413524, 6285.717142728497, 6286.622802631857, 6286.899792536196, 6290.937090629035, 5773.566982476247, 5882.147088672033, 6003.45968862787, 6006.0, 6106.358832322542, 6028.909566356596, 6078.179445774941, 6086.300075491198, 6091.634501929899, 6113.877722445437, 5846.0, 6101.420365065312, 6114.969425152845, 6115.0, 6120.908186377119, 5959.642421454727, 6030.668313242638, 6037.45662890342, 6041.697915104315, 6050.394960503914, 6027.0, 6047.304559975483, 6050.364711705208, 6058.636481811105, 6061.242344706911], "value": 5707.279451097957, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.9515781474204, 832.6544484039124, 848.4739461533848, 916.1983264643437, 929.4098247612764, 31001.68998310019, 44116.779416102916, 45165.77417112914, 45167.322490162675, 45294.0, 46588.30117548231, 46633.30050049252, 46716.29925551111, 46840.29739553909, 46940.53059469404, 7901.525908445512, 8996.145366190201, 9124.041975592567, 9130.04134565871, 9136.406133601295, 7850.136484986676, 10662.360258384522, 10724.517396717127, 10736.73158171048, 10811.675649730521, 10835.566577336896, 10857.51141198644, 10858.511366988505, 10860.511276992514, 10868.565257389695, 10772.138228941665, 10776.030157285804, 10800.78398432027, 10805.027547520722, 10806.459677016197, 10402.79194416115, 10436.37381757097, 10448.738781530446, 10453.738656533571, 10460.947695261551, 43583.56416435827, 43604.345934811005, 43739.0, 43792.562074379166, 44013.33979990303, 4067.369557718529, 4114.814833332507, 4132.834686612532, 4224.753697659198, 4254.829806807724, 29113.708862911313, 29716.85141574278, 29894.85052574745, 30139.09582712522, 30160.396792064254, 4281.12236991416, 4379.64962802975, 4408.779561021936, 4412.0955204183065, 4516.458025036984, 234190.0, 236915.15424228893, 237129.0, 237756.0, 238440.99999999825, 53708.731456342466, 54017.72991135098, 54024.45975540194, 54079.18881216785, 54296.72851635796, 54964.17553736697, 54976.0, 55123.0, 55209.447905521236, 55292.44707552873, 16292.103934283728, 16696.58258543522, 16714.08072555997, 16742.916285418494, 16757.83242167587, 12865.742685146432, 13137.408816603276, 14758.188299643409, 14816.259187040605, 14856.554303370916, 13086.280254586089, 13453.865461345456, 13684.0, 13736.175829450265, 13871.306434678225, 5439.592030597721, 5449.945500545023, 5545.445455454503, 5580.186439407209, 5582.190582365534, 1033.1373303291834, 1035.9430231337199, 1038.2368958815264, 1046.931949423285, 1055.5091882274778, 28324.291892702848, 28468.43063138767, 28645.856770716015, 28764.849406023943, 29127.0, 1092.8907109289007, 1098.1105304703217, 1112.7218195451137, 1136.5169802833764, 1167.0046055534322, 15688.607784805243, 16695.332186712396, 17017.744733829004, 17353.264673532547, 17877.7318340225, 333484.0, 335051.0, 335325.0, 335820.0, 337131.0, 59447.10829337563, 59574.0, 59622.40377596255, 59759.0, 59838.70080649569, 1526.6412393087614, 1532.762421824619, 1549.349273305192, 1552.3945661192145, 1553.3398305720025, 29713.554296685135, 31031.689683103785, 31322.843385782926, 31586.210344740644, 31590.0, 41975.58024419717, 42634.5736542643, 42671.57328426676, 42720.0, 42736.1452770949, 287195.0, 288273.0, 290753.0, 290985.0, 291723.0, 28065.579016314365, 28756.281092972844, 32008.67991320057, 33578.49632255469, 34671.479927800596, 45660.315095272934, 45849.31226031679, 45880.31179532377, 46126.0, 46238.76880615576, 33210.50184247286, 33622.0, 33679.494807577415, 33741.0, 33754.493682594286, 33827.0, 34029.31941361109, 34056.0, 34318.48522272218, 34388.14029649279, 39263.0, 39472.40791388074, 39537.209255814145, 40057.0, 40115.799421003874, 32830.83584582062, 32998.17504562405, 33118.0, 33151.50272745959, 33498.162545936546, 24870.50258994846, 26633.201003969913, 26694.13141080106, 26761.464770704868, 26775.330616733958], "value": 19354.37230738086, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.1754429747941, 478.8467690339093, 481.1483673897202, 483.48267353931266, 491.1478584655622, 19625.214991400328, 21058.789412105874, 21155.365339039836, 21347.0, 21393.893030534844, 21613.67579486305, 22256.8887155564, 22478.55042899145, 22525.436864078405, 22788.430289242737, 4942.901141977159, 6448.45188159007, 6642.900356494656, 6715.429188518981, 6742.696578653972, 4249.553796851333, 4424.889377765549, 6601.834954126137, 7088.326608972139, 7370.8894366584545, 7201.67592458338, 7557.357624601916, 7565.6217189140325, 7571.734989275376, 7577.734779282726, 7520.0, 7609.315161635424, 7615.390768738488, 7615.657295421747, 7624.809379765523, 7243.0, 7446.776596702108, 7463.813404664873, 7480.102387713455, 7483.0, 25220.773916551796, 25805.870970645214, 26027.73972260272, 26080.73919260821, 26089.0, 3330.300636866261, 3501.6498350165034, 3510.3501354363407, 3548.716102711777, 3608.585012723544, 15607.297671604692, 15645.217739112999, 16064.839351606452, 16073.115978621172, 16555.834441655672, 2132.9228739486616, 2180.291405293286, 2202.6916231727573, 2215.8116560092412, 2238.29493709481, 108890.0, 111860.0, 112565.43717281443, 113011.43494282477, 114904.42547787291, 17424.91287543554, 17744.822551774574, 17974.91012544955, 17992.370267040853, 18281.634367312847, 31915.361692766015, 32047.839760801045, 32085.839570801996, 32160.0, 32417.189570260933, 10712.892871071346, 10802.51388687511, 10803.189760767817, 10835.891641083488, 11007.174461915389, 9245.953770231106, 9258.0, 9269.304802139866, 9450.57472413743, 9493.477858717837, 8883.511406872554, 9262.351635385457, 9387.671431499864, 9477.66828161025, 9519.143277105093, 3136.388404261147, 3645.3802853514967, 3902.6292502212245, 3968.861089861841, 4052.1287923096675, 948.7533241357297, 963.5952899782109, 971.5822196455592, 976.8974257702839, 981.1267971505366, 19273.132709028127, 20487.69268460974, 20489.18043278282, 20515.17939282442, 20757.688634670492, 977.4672803322206, 978.5449765858767, 993.9353941993747, 997.2122023601419, 1002.734275417015, 10762.300450470695, 11474.254173478697, 11598.88401115995, 11617.477213525412, 11701.414929253504, 680853.0, 689525.0, 691463.0, 740293.0, 746747.0, 113354.43322783333, 114917.4254128724, 116439.41780291044, 117460.0, 120640.3967980172, 1244.5581818454318, 1257.798752199662, 1268.340462959256, 1281.448976939906, 1282.3524120319366, 17439.82560174382, 17837.286508539513, 18073.36743213981, 18290.26838926428, 18312.175952082456, 49640.503594963586, 49925.75037124936, 50187.498125019745, 50469.0, 50526.24210636917, 64013.3598664026, 64643.35356646373, 65879.67060164668, 66021.66989165024, 67067.32932670806, 12234.816477752664, 12370.876291236973, 12411.813822792485, 12439.813402799145, 12642.304673242692, 14089.506867259597, 14103.506377277157, 14159.433622654979, 14226.715465690835, 14267.857321426653, 11673.416329183337, 12410.875891240972, 12529.436175371948, 12551.246925184518, 12936.870631293943, 10691.5188816505, 10806.297590656583, 10821.350718956888, 10848.294860834336, 10871.51078201467, 11886.346250955934, 12305.56930507428, 12323.50705971751, 12481.188722732993, 12814.249970213228, 11818.46816893259, 11820.70448238801, 12001.1599188056, 12123.272603643809, 12159.148859579744, 11918.52325906954, 12530.937345313216, 12570.0, 12823.0, 12863.549775757803], "value": 11505.977702558801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1576.3615735627068, 1578.1241411016886, 1578.1714599835077, 1586.5002524204879, 1592.068639845691, 10501.527431265593, 11305.039071678897, 11306.038986686142, 11307.886921130796, 11311.490982905758, 11092.168087393458, 11336.773264534704, 11360.715982100452, 11374.601888933888, 11389.373584452851, 2479.876006199692, 2521.6343630173615, 2525.078346403568, 2525.6590360301307, 2525.911593094242, 2445.119756887519, 2488.14159115105, 2519.937001574966, 2522.672052633155, 2524.911628093017, 2458.4714286428416, 2481.9751802482015, 2507.285423654259, 2556.782673472757, 2558.2836805694437, 2399.904003839853, 2440.2557220047966, 2448.6327050942414, 2449.4856080223176, 2455.1897873701637, 2342.8594284342994, 2360.3391050505716, 2363.692719946413, 2368.171140100968, 2389.2712722619594, 11232.719182020433, 11303.773924521463, 11312.94343528277, 11328.263662861887, 11334.773304533863, 5753.137029445575, 5841.970790146064, 5885.117232415129, 5886.323072846634, 5888.558358123158, 3529.5235143255845, 3597.442396428558, 3600.1899572596217, 3658.9085272868124, 3698.149425632093, 1497.139144991634, 2274.5678321118935, 2321.4312493439215, 2352.5177338645535, 2380.5357955198742, 14528.055676381002, 14861.777073343908, 16260.59348516273, 16696.24689407607, 16857.0, 4757.167495688241, 4826.565609095198, 4918.43438004634, 4943.233798761198, 4974.37820272466, 12763.425645845959, 12876.871231287754, 12923.28921909304, 12930.612081637566, 12935.805962910565, 6052.303985041688, 6067.332593414721, 6071.787487437919, 6073.119397687311, 6076.726547305382, 5582.497575218169, 5595.4124816894455, 5608.0, 5609.943900561024, 5685.147227915763, 4364.519902810688, 4372.169287835365, 4457.0, 4511.075229577928, 4523.0, 1192.66605350502, 1226.4235809169677, 1234.5000274888685, 1240.6030070377574, 1241.4103300932165, 968.6028728221395, 1013.5185786751382, 1020.0615433800921, 1023.9334443261164, 1032.963846265377, 7111.679974401164, 7188.892166617505, 7199.0, 7205.207427183006, 7213.088531539146, 990.6037584966072, 1022.1260821997085, 1039.142707266508, 1041.6354276003337, 1057.4448414582405, 4455.376247325377, 4503.07300254837, 4566.794494247767, 4781.713097214179, 4810.2063159578975, 13929.651758706113, 14543.92728036353, 14555.272236388138, 14556.126632401878, 14594.927025365017, 13887.375068121959, 14361.138331700131, 14787.482438114614, 14823.184724840237, 14826.777598336033, 8843.646254149762, 10590.788184236271, 10774.138068954622, 10790.460476976119, 10831.241813073168, 6068.575199735976, 7094.751683691253, 7162.2121566628675, 7282.453815963718, 7515.812104697207, 11268.7746245073, 11314.208005439541, 11365.0, 11371.090312774793, 11575.42122893835, 12220.51117955272, 12291.9385403076, 12344.321062341658, 12349.93825030869, 12350.135490515577, 9654.27592930519, 9817.460039697886, 9857.704268871945, 9906.157976572073, 9906.900930990887, 10658.520366583678, 10707.839382409426, 10739.087177589992, 10760.784784304427, 10917.39954302521, 9227.400218986013, 9298.395604285699, 9440.811183776148, 9628.47043412591, 9667.274954378308, 8671.609777560152, 8821.117888211127, 8927.642894284156, 8931.73204803857, 9214.493202873638, 8161.877571836546, 8196.672133114847, 8207.0, 8217.630206640835, 8220.958895205486, 5800.9419905800405, 5873.471387575139, 5942.554308426799, 5947.464728174485, 6063.605865618721, 5808.564357673107, 5884.293884733859, 5911.615744976562, 5961.493273071664, 6073.271207455134], "value": 5454.135085702143, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.8752374287713, 418.9895252618686, 438.563629188957, 444.9955500444995, 451.42668810610525, 5068.0370729561355, 5126.487351264872, 5146.81986130486, 5172.534471897528, 5211.0, 5000.549950504454, 5038.9748051259785, 5058.570021548173, 5082.941408729461, 5143.974280128604, 4285.142971405716, 4288.976271728424, 4298.0, 4303.35449682547, 4347.152305300468, 4322.0, 4326.783660816962, 4331.43691320128, 4346.608805207531, 4348.91302173958, 4178.847893334935, 4280.5291417944, 4296.625079974409, 4297.355396690506, 4299.763513006784, 4261.76560289184, 4267.738098807637, 4272.978635106821, 4285.442892423965, 4292.420523229371, 4031.395290706388, 4053.635172834459, 4055.3916912463073, 4055.472788537501, 4091.115763386805, 4818.469968303484, 5063.949360506384, 5066.392032956032, 5091.745412729349, 5095.248565774477, 4989.727619457015, 4994.750262486898, 5014.974925125388, 5074.822381216677, 5082.593392528589, 5381.946180538183, 5397.919031214535, 5402.459754024605, 5426.403095659474, 5437.864053398657, 4364.2798938175165, 4444.73331600105, 4475.910481790346, 4498.685092043558, 4507.977460112711, 5329.6002799789785, 5415.268938693265, 5508.641938274, 5593.272874526327, 5600.663960162363, 4579.9084018319445, 4590.0, 4596.3105534170145, 4596.908061838744, 4609.285560738091, 4527.569880861313, 4596.678232523691, 4657.138429390574, 4660.83063516805, 4692.6245900327895, 4935.568685081358, 4978.651494395358, 4983.451820299764, 4992.550670439678, 5016.372953380827, 5326.414094449608, 5351.839444816662, 5355.598330125256, 5355.678659280456, 5371.785128594813, 1542.7608720648318, 1569.7331453652907, 1580.5495433801311, 1592.6894255620043, 1621.27853105367, 1202.0023380594262, 1227.5396726227664, 1233.975320493603, 1245.651217659057, 1257.2456526084459, 5226.320578324793, 5253.605979551549, 5297.364316282071, 5392.191171324254, 5394.487523685244, 5423.484768946943, 5522.66863988162, 5545.223668686387, 5569.19246709233, 5629.802956896489, 5073.847784566469, 5368.275282836845, 5382.811601593926, 5399.0, 5409.486098820606, 2921.9707802922126, 2922.6346706661666, 2926.9121926342245, 2929.7363237308746, 2937.7796665250194, 5600.355959064678, 5709.457601527848, 5715.485606295453, 5785.768569257267, 5795.420457954255, 5564.8330550083565, 5577.414371491016, 5603.971980140154, 5616.438356164433, 5654.897295027512, 5735.340435849931, 5746.224259724883, 5748.597598168171, 5763.250777398911, 5764.550066221923, 5285.418603953485, 5402.486763757437, 5486.506214440719, 5502.944970550403, 5507.972460137674, 5247.294629245587, 5325.920111198413, 5385.757640906247, 5435.211894275387, 5465.699386533779, 5737.454941780524, 5747.8563035924435, 5764.567657425626, 5786.4213578643485, 5815.534757219328, 4492.685512014127, 4626.236670949255, 4640.0, 4644.9767751161025, 4651.534846515321, 5443.530246833254, 5459.672419654833, 5499.340079190522, 5571.387147413862, 5588.469095435927, 5348.897639369079, 5359.311816777703, 5396.9460305398015, 5410.675359478287, 5442.619016668795, 5197.792088316576, 5386.919196212139, 5392.946070539245, 5407.970587353799, 5410.998965191452, 5422.674639521641, 5440.646357986719, 5445.7549410277425, 5452.509274165344, 5459.645123066988, 5424.945750542602, 5447.645903016291, 5455.536279416294, 5472.72636368188, 5511.834644960658, 5113.667611605234, 5307.761150748149, 5319.547838433777, 5326.414094449685, 5358.6248962572245], "value": 4348.423459650449, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.4571516753533, 864.9783755406111, 897.6544676451729, 905.9773505662358, 924.8011677489343, 16123.677526449464, 16286.511404657864, 16327.51017469475, 16338.264778084986, 16651.66696666066, 15631.218439078057, 15695.0, 15741.763873541906, 15750.212489375543, 16692.74960875588, 849.2102344819331, 851.6933903794629, 863.8142799298149, 864.4899509289518, 865.139186509424, 864.5201912938317, 876.4609764994547, 877.4691311756392, 877.4779006491127, 877.7322916510461, 908.0329449136682, 908.7864351877304, 909.5043201455189, 909.9408538445012, 910.1899309614448, 907.6732376344511, 907.686848037426, 908.4231512989234, 908.6183802802806, 908.6320040383622, 665.5341261117189, 666.7999600119945, 666.986660266792, 667.2393471442559, 667.6461475418037, 16733.497995060166, 16987.660246794996, 17138.14309284531, 17696.557586060324, 17909.820901791078, 13938.233397163152, 14032.158070515801, 14160.645983850383, 14172.929135354258, 14529.491467798682, 13826.516071937434, 13875.37560809766, 14026.85973140276, 14152.0, 14209.644758881112, 3933.468981687493, 3945.506811648544, 3969.821358038895, 3972.145988612462, 4095.610916962884, 51143.74428127873, 51893.22160167601, 55499.0, 73526.63236683836, 93330.0, 10731.248812583044, 11158.1631377647, 11289.604863829727, 11412.771744565229, 11538.0, 19787.20851165966, 19977.700334494995, 20092.0, 20188.293409730588, 20282.59434811325, 8410.705625303208, 8450.239478446969, 8597.18326758957, 8735.213830755263, 8854.64581416749, 4330.090680957034, 4401.581849724271, 4426.136903303889, 4434.512203657595, 4436.112777444524, 3851.537815462106, 3958.2281455116554, 4077.55146933843, 4135.255653982312, 4142.420061191376, 686.8282929267683, 687.700850130195, 688.7348370877248, 713.8215446138465, 732.3189433826592, 785.8938046036676, 786.4573444323398, 789.6683392974965, 792.1998781230998, 816.9632366543519, 11041.944790275997, 11178.329300242012, 11380.203385762918, 11408.144389170846, 11462.197646164688, 676.1413005483009, 678.4504551313452, 692.069166970424, 692.1866806502339, 694.0595493106869, 4680.274557443602, 4816.301636262722, 4820.975895120502, 4846.006568653417, 4850.345203397566, 81841.18158818455, 81977.59011205025, 82246.0, 83881.0, 84491.0, 43701.78149109234, 49125.50874491209, 59771.70114149489, 76376.23623763802, 78211.0, 59794.0, 60971.0, 60984.0, 61057.69471152616, 61233.0, 11133.443327833738, 11560.0, 11646.41767911584, 11863.110266729856, 11901.0, 41430.37854432246, 42911.0, 42975.0, 43373.56626433822, 43733.34399984066, 49262.75368623134, 51097.0, 51616.22575661443, 51992.220116699034, 55935.0, 12914.80627790565, 13145.277009764555, 13468.86531134676, 13802.447902083804, 14128.576142715736, 14977.550673479813, 16428.42500512476, 16433.424830130887, 17300.22149003323, 17390.47828565145, 15406.998545094533, 15728.528144155694, 16359.427420040241, 16568.668626627157, 16583.585410364838, 14300.427982880568, 14387.640308992359, 14484.710305793615, 14645.70708585801, 15378.154201519024, 13455.730885382432, 13875.86124138786, 13965.790513142514, 14384.712305754036, 15093.24533773285, 9239.676611318571, 9712.969117816026, 9793.412395256308, 9844.114029737359, 10151.949240254047, 12310.876891230973, 12769.319446653004, 12803.0, 12812.423440945366, 12910.612681619567], "value": 7572.7048523559415, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1462.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 113.0, 113.0, 120.0, 113.0, 112.0, 113.0, 112.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 121.0, 151.0, 115.0, 117.0, 115.0, 119.0, 134.0, 116.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 161.0, 161.0, 163.0, 163.0, 162.0, 161.0, 161.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 109.0, 76.0, 76.0, 76.0, 76.0, 89.0, 74.0], "value": 80.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 113.0, 82.0, 82.0, 83.0, 105.0, 82.0, 83.0, 105.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 55.0, 55.0, 56.0, 56.0, 55.0, 55.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 53.0, 54.0, 54.0, 54.0, 54.0, 54.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 80.0, 79.0, 84.0, 83.0, 76.0, 84.0, 78.0, 80.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 124.0, 123.0, 131.0, 123.0, 125.0, 119.0, 121.0, 160.0, 121.0], "value": 127.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 163.0, 162.0, 173.0, 163.0, 158.0, 159.0, 165.0, 165.0], "value": 162.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 61.0, 61.0, 62.0, 63.0, 63.0, 59.0, 58.0], "value": 61.1, "unit": "score"}]}]} 13:07:57 INFO - Return code: 0 13:07:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:07:57 ERROR - # TBPL SUCCESS # 13:07:57 INFO - Running post-action listener: _resource_record_post_action 13:07:57 INFO - [mozharness: 2017-04-11 20:07:57.262348Z] Finished run-tests step (success) 13:07:57 INFO - Running post-run listener: _resource_record_post_run 13:07:57 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 266240; Write bytes: 374689792; Read time: 32; Write time: 318304 13:07:57 INFO - TinderboxPrint: CPU usage
13.2% 13:07:57 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 32 13:07:57 INFO - TinderboxPrint: I/O write bytes / time
374,689,792 / 318,304 13:07:57 INFO - TinderboxPrint: CPU idle
8,276.2 (86.8%) 13:07:57 INFO - TinderboxPrint: CPU user
1,203.2 (12.6%) 13:07:57 INFO - TinderboxPrint: Swap in / out
0 / 0 13:07:57 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 123060224; Read time: 0; Write time: 141724 13:07:57 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 266240; Write bytes: 251629568; Read time: 32; Write time: 176580 13:07:57 INFO - Running post-run listener: _upload_blobber_files 13:07:57 INFO - Blob upload gear active. 13:07:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:07:57 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 13:07:57 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:07:57 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:07:58 INFO - (blobuploader) - INFO - Open directory for files ... 13:07:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:07:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:07:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:07:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:07:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:07:59 INFO - (blobuploader) - INFO - Done attempting. 13:07:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:07:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:07:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:08:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:00 INFO - (blobuploader) - INFO - Done attempting. 13:08:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:08:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:08:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:00 INFO - (blobuploader) - INFO - Done attempting. 13:08:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:08:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:08:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:05 INFO - (blobuploader) - INFO - Done attempting. 13:08:05 INFO - (blobuploader) - INFO - Iteration through files over. 13:08:05 INFO - Return code: 0 13:08:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:08:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:08:05 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aec0020b850beacbcb3cc7c5726d6a36df510e2c3d2b8364d39414daf83b0ab20fa20d930b367c93d984c8b4e625a8ffd8028cd0db47cb5a97a9b835462b23b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2d87d4416062e43acfa0a56a6e8b3c3d4aeb93b8ff1cced892372963c4e0bd60c776da814026f779da8b1987a694b4af4b7f92071aaaf269cf17bccfe273ffe", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c5ab3a2efce7c1830718ba5ca35e17e67e012b5440b4b4474aef6311bc340926238a1b28d8057f0d2ff8a052a477640c499df86c66d8502db80e6d932cdebb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/805d75330a95d1662ad89f179022022aab91f5c3229cfd940126de8c9cc03ceee1b64d6a332249d22044b9d9e586e3e0853ac7eb7105eb3f94225f8c2cd69333"} 13:08:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:08:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:08:05 INFO - Contents: 13:08:05 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aec0020b850beacbcb3cc7c5726d6a36df510e2c3d2b8364d39414daf83b0ab20fa20d930b367c93d984c8b4e625a8ffd8028cd0db47cb5a97a9b835462b23b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2d87d4416062e43acfa0a56a6e8b3c3d4aeb93b8ff1cced892372963c4e0bd60c776da814026f779da8b1987a694b4af4b7f92071aaaf269cf17bccfe273ffe", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c5ab3a2efce7c1830718ba5ca35e17e67e012b5440b4b4474aef6311bc340926238a1b28d8057f0d2ff8a052a477640c499df86c66d8502db80e6d932cdebb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/805d75330a95d1662ad89f179022022aab91f5c3229cfd940126de8c9cc03ceee1b64d6a332249d22044b9d9e586e3e0853ac7eb7105eb3f94225f8c2cd69333"} 13:08:05 INFO - Running post-run listener: copy_logs_to_upload_dir 13:08:05 INFO - Copying logs to upload dir... 13:08:05 INFO - mkdir: /builds/slave/test/build/upload/logs 13:08:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.782115 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-11 13:08:05.043405) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 13:08:05.046571) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339 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/sha512/aec0020b850beacbcb3cc7c5726d6a36df510e2c3d2b8364d39414daf83b0ab20fa20d930b367c93d984c8b4e625a8ffd8028cd0db47cb5a97a9b835462b23b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2d87d4416062e43acfa0a56a6e8b3c3d4aeb93b8ff1cced892372963c4e0bd60c776da814026f779da8b1987a694b4af4b7f92071aaaf269cf17bccfe273ffe", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c5ab3a2efce7c1830718ba5ca35e17e67e012b5440b4b4474aef6311bc340926238a1b28d8057f0d2ff8a052a477640c499df86c66d8502db80e6d932cdebb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/805d75330a95d1662ad89f179022022aab91f5c3229cfd940126de8c9cc03ceee1b64d6a332249d22044b9d9e586e3e0853ac7eb7105eb3f94225f8c2cd69333"} build_url:https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011422 build_url: 'https://queue.taskcluster.net/v1/task/NSWdPTxlTeK0L1F7Zqsozw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aec0020b850beacbcb3cc7c5726d6a36df510e2c3d2b8364d39414daf83b0ab20fa20d930b367c93d984c8b4e625a8ffd8028cd0db47cb5a97a9b835462b23b9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c2d87d4416062e43acfa0a56a6e8b3c3d4aeb93b8ff1cced892372963c4e0bd60c776da814026f779da8b1987a694b4af4b7f92071aaaf269cf17bccfe273ffe", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2c5ab3a2efce7c1830718ba5ca35e17e67e012b5440b4b4474aef6311bc340926238a1b28d8057f0d2ff8a052a477640c499df86c66d8502db80e6d932cdebb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/805d75330a95d1662ad89f179022022aab91f5c3229cfd940126de8c9cc03ceee1b64d6a332249d22044b9d9e586e3e0853ac7eb7105eb3f94225f8c2cd69333"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 13:08:05.072599) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 13:08:05.072990) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1491940030.353620-1072132339 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004438 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 13:08:05.155314) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 13:08:05.155666) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 13:08:05.155999) ========= ========= Total master_lag: 0.17 =========