builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-019 starttime: 1493025228.65 results: success (0) revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:48.649314) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:48.649807) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:48.650091) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:48.668043) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:48.668307) ========= 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-1493021468.957087-1264642343 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-24 02:13:49-- 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% 217M=0s 2017-04-24 02:13:49 (217 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131489 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:48.818144) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:48.818495) ========= 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-1493021468.957087-1264642343 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014869 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:48.873642) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:48.873933) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-24 02:13:49,337 truncating revision to first 12 chars 2017-04-24 02:13:49,337 Setting DEBUG logging. 2017-04-24 02:13:49,337 attempt 1/10 2017-04-24 02:13:49,337 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-24 02:13:49,757 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.786195 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:49.673911) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:49.674232) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:49.674606) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:49.674869) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 02:13:49.689007) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-04-24 02:13:49.689285) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:13:50 INFO - MultiFileLogger online at 20170424 02:13:50 in /builds/slave/test 02:13:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 02:13:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:13:50 INFO - {'append_to_log': False, 02:13:50 INFO - 'base_work_dir': '/builds/slave/test', 02:13:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 02:13:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:13:50 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 02:13:50 INFO - 'buildbot_json_path': 'buildprops.json', 02:13:50 INFO - 'config_files': ('talos/linux_config.py',), 02:13:50 INFO - 'default_actions': ('clobber', 02:13:50 INFO - 'read-buildbot-config', 02:13:50 INFO - 'download-and-extract', 02:13:50 INFO - 'populate-webroot', 02:13:50 INFO - 'create-virtualenv', 02:13:50 INFO - 'install', 02:13:50 INFO - 'run-tests'), 02:13:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:13:50 INFO - 'download_minidump_stackwalk': True, 02:13:50 INFO - 'download_symbols': 'ondemand', 02:13:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:13:50 INFO - 'tooltool.py': '/tools/tooltool.py', 02:13:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:13:50 INFO - '/tools/misc-python/virtualenv.py')}, 02:13:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:13:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:13:50 INFO - 'gecko_profile': False, 02:13:50 INFO - 'gecko_profile_interval': 0, 02:13:50 INFO - 'installer_path': 'installer.exe', 02:13:50 INFO - 'log_level': 'info', 02:13:50 INFO - 'log_name': 'talos', 02:13:50 INFO - 'log_to_console': True, 02:13:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:13:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:13:50 INFO - 'opt_config_files': (), 02:13:50 INFO - 'pip_index': False, 02:13:50 INFO - 'suite': 'dromaeojs', 02:13:50 INFO - 'system_bits': '32', 02:13:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:13:50 INFO - 'title': 'talos-linux64-ix-019', 02:13:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:13:50 INFO - 'use_talos_json': True, 02:13:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:13:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:13:50 INFO - 'work_dir': 'build'} 02:13:50 INFO - [mozharness: 2017-04-24 09:13:50.253574Z] Running clobber step. 02:13:50 INFO - Running pre-action listener: _resource_record_pre_action 02:13:50 INFO - Running main action method: clobber 02:13:50 INFO - rmtree: /builds/slave/test/build 02:13:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:13:51 INFO - Running post-action listener: _resource_record_post_action 02:13:51 INFO - [mozharness: 2017-04-24 09:13:51.183097Z] Finished clobber step (success) 02:13:51 INFO - [mozharness: 2017-04-24 09:13:51.183198Z] Running read-buildbot-config step. 02:13:51 INFO - Running pre-action listener: _resource_record_pre_action 02:13:51 INFO - Running main action method: read_buildbot_config 02:13:51 INFO - Using buildbot properties: 02:13:51 INFO - { 02:13:51 INFO - "project": "", 02:13:51 INFO - "product": "firefox", 02:13:51 INFO - "who": "nobody@mozilla.org", 02:13:51 INFO - "installer_path": "public/build/target.tar.bz2", 02:13:51 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 02:13:51 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora", 02:13:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 02:13:51 INFO - "stage_platform": "linux64", 02:13:51 INFO - "basedir": "/builds/slave/test", 02:13:51 INFO - "buildnumber": 139, 02:13:51 INFO - "platform": "ubuntu64_hw", 02:13:51 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:13:51 INFO - "slavebuilddir": "test", 02:13:51 INFO - "taskId": "H-P-Cuk9TeSFaJmEWJRK2w", 02:13:51 INFO - "branch": "mozilla-aurora", 02:13:51 INFO - "script_repo_revision": "production", 02:13:51 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 02:13:51 INFO - "slavename": "talos-linux64-ix-019", 02:13:51 INFO - "repo_path": "releases/mozilla-aurora" 02:13:51 INFO - } 02:13:51 INFO - Finding installer, test and symbols from parent task. 02:13:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H-P-Cuk9TeSFaJmEWJRK2w'}, attempt #1 02:13:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H-P-Cuk9TeSFaJmEWJRK2w'}, attempt #1 02:13:52 INFO - Task dependencies: QS-XteUnTw23ach84BtEig 02:13:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig'}, attempt #1 02:13:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.tar.bz2 02:13:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/test_packages.json 02:13:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.crashreporter-symbols.zip 02:13:52 INFO - Running post-action listener: _resource_record_post_action 02:13:52 INFO - [mozharness: 2017-04-24 09:13:52.548969Z] Finished read-buildbot-config step (success) 02:13:52 INFO - [mozharness: 2017-04-24 09:13:52.549187Z] Running download-and-extract step. 02:13:52 INFO - Running pre-action listener: _resource_record_pre_action 02:13:52 INFO - Running main action method: download_and_extract 02:13:52 INFO - mkdir: /builds/slave/test/build/tests 02:13:52 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')]} 02:13:52 INFO - trying https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.test_packages.json 02:13:52 INFO - Downloading https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:13:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:13:53 INFO - Downloaded 896 bytes. 02:13:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:13:53 INFO - Using the following test package requirements: 02:13:53 INFO - {u'common': [u'target.common.tests.zip'], 02:13:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:13:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:13:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:13:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:13:53 INFO - u'mozbase': [u'target.common.tests.zip'], 02:13:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:13:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:13:53 INFO - u'web-platform': [u'target.common.tests.zip', 02:13:53 INFO - u'target.web-platform.tests.zip'], 02:13:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:13:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 02:13:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.common.tests.zip 02:13:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:13:53 INFO - Fetch https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.common.tests.zip into memory 02:13:55 INFO - Expected file size: 59539809 02:13:55 INFO - Obtained file size: 59539809 02:14:05 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 02:14:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.common.tests.zip 02:14:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:14:05 INFO - Fetch https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.common.tests.zip into memory 02:14:06 INFO - Expected file size: 59539809 02:14:06 INFO - Obtained file size: 59539809 02:14:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.talos.tests.zip 02:14:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:14:17 INFO - Fetch https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.talos.tests.zip into memory 02:14:18 INFO - Expected file size: 14404424 02:14:18 INFO - Obtained file size: 14404424 02:14:18 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')]} 02:14:18 INFO - trying https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.tar.bz2 02:14:18 INFO - Downloading https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:14:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:14:21 INFO - Downloaded 66688496 bytes. 02:14:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.tar.bz2 02:14:21 INFO - mkdir: /builds/slave/test/properties 02:14:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:14:21 INFO - Writing to file /builds/slave/test/properties/build_url 02:14:21 INFO - Contents: 02:14:21 INFO - build_url:https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.tar.bz2 02:14:21 INFO - Running post-action listener: _resource_record_post_action 02:14:21 INFO - Running post-action listener: set_extra_try_arguments 02:14:21 INFO - [mozharness: 2017-04-24 09:14:21.228332Z] Finished download-and-extract step (success) 02:14:21 INFO - [mozharness: 2017-04-24 09:14:21.228521Z] Running populate-webroot step. 02:14:21 INFO - Running pre-action listener: _resource_record_pre_action 02:14:21 INFO - Running main action method: populate_webroot 02:14:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:14:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:14:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:14:21 INFO - '--tppagecycles', 02:14:21 INFO - '7'], 02:14:21 INFO - 'tests': ['tsvgm']}}, 02:14:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:14:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:14:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:14:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:14:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:14:21 INFO - 'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:14:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:14:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:14:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:14:21 INFO - 'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['damp', 'tps']}, 02:14:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:14:21 INFO - 'tests': ['damp', 'tps']}, 02:14:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['dromaeo_dom']}, 02:14:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:14:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['basic_compositor_video']}, 02:14:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:14:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['a11yr', 02:14:21 INFO - 'ts_paint', 02:14:21 INFO - 'tpaint', 02:14:21 INFO - 'sessionrestore', 02:14:21 INFO - 'sessionrestore_no_auto_restore', 02:14:21 INFO - 'tabpaint']}, 02:14:21 INFO - 'other-e10s': {'tests': ['a11yr', 02:14:21 INFO - 'ts_paint', 02:14:21 INFO - 'tpaint', 02:14:21 INFO - 'sessionrestore', 02:14:21 INFO - 'sessionrestore_no_auto_restore', 02:14:21 INFO - 'tabpaint']}, 02:14:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:14:21 INFO - 'ts_paint', 02:14:21 INFO - 'tpaint', 02:14:21 INFO - 'sessionrestore', 02:14:21 INFO - 'sessionrestore_no_auto_restore', 02:14:21 INFO - 'tabpaint']}, 02:14:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:14:21 INFO - 'ts_paint', 02:14:21 INFO - 'tpaint', 02:14:21 INFO - 'sessionrestore', 02:14:21 INFO - 'sessionrestore_no_auto_restore', 02:14:21 INFO - 'tabpaint']}, 02:14:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['a11yr', 02:14:21 INFO - 'ts_paint', 02:14:21 INFO - 'tpaint', 02:14:21 INFO - 'sessionrestore', 02:14:21 INFO - 'sessionrestore_no_auto_restore', 02:14:21 INFO - 'tabpaint']}, 02:14:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['a11yr', 02:14:21 INFO - 'ts_paint', 02:14:21 INFO - 'tpaint', 02:14:21 INFO - 'sessionrestore', 02:14:21 INFO - 'sessionrestore_no_auto_restore', 02:14:21 INFO - 'tabpaint']}, 02:14:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['tsvgx', 02:14:21 INFO - 'tsvgr_opacity', 02:14:21 INFO - 'tart', 02:14:21 INFO - 'tscrollx', 02:14:21 INFO - 'cart', 02:14:21 INFO - 'tsvg_static']}, 02:14:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:14:21 INFO - 'tsvgr_opacity', 02:14:21 INFO - 'tart', 02:14:21 INFO - 'tscrollx', 02:14:21 INFO - 'cart', 02:14:21 INFO - 'tsvg_static']}, 02:14:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:14:21 INFO - 'talos_options': ['--disable-e10s'], 02:14:21 INFO - 'tests': ['tp5o']}, 02:14:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:14:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:14:21 INFO - 'talos_options': ['--disable-e10s', 02:14:21 INFO - '--xperf_path', 02:14:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:14:21 INFO - 'tests': ['tp5n']}, 02:14:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:14:21 INFO - 'talos_options': ['--xperf_path', 02:14:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:14:21 INFO - 'tests': ['tp5n']}}, 02:14:21 INFO - 'talos.zip': {'path': '', 02:14:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:14:21 INFO - Running post-action listener: _resource_record_post_action 02:14:21 INFO - [mozharness: 2017-04-24 09:14:21.250580Z] Finished populate-webroot step (success) 02:14:21 INFO - [mozharness: 2017-04-24 09:14:21.250671Z] Running create-virtualenv step. 02:14:21 INFO - Running pre-action listener: _resource_record_pre_action 02:14:21 INFO - Running main action method: create_virtualenv 02:14:21 INFO - Creating virtualenv /builds/slave/test/build/venv 02:14:21 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 02:14:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:14:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:14:21 INFO - Using real prefix '/usr' 02:14:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:14:21 INFO - Installing distribute.............................................................................................................................................................................................done. 02:14:23 INFO - Installing pip.................done. 02:14:23 INFO - Return code: 0 02:14:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:14:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:14:24 INFO - Reading from file tmpfile_stdout 02:14:24 INFO - Output received: 02:14:24 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) 02:14:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:14:24 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')]} 02:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:24 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')]} 02:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:24 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 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:24 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 02:14:24 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 02:14:24 INFO - Using env: {'DISPLAY': ':0', 02:14:24 INFO - 'HOME': '/home/cltbld', 02:14:24 INFO - 'LANG': 'en_US.UTF-8', 02:14:24 INFO - 'LANGUAGE': 'en_US:en', 02:14:24 INFO - 'LOGNAME': 'cltbld', 02:14:24 INFO - 'MAIL': '/var/mail/cltbld', 02:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:24 INFO - 'MOZ_NO_REMOTE': '1', 02:14:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:24 INFO - 'NO_EM_RESTART': '1', 02:14:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:24 INFO - 'PWD': '/builds/slave/test', 02:14:24 INFO - 'SHELL': '/bin/bash', 02:14:24 INFO - 'SHLVL': '1', 02:14:24 INFO - 'TERM': 'linux', 02:14:24 INFO - 'TMOUT': '86400', 02:14:24 INFO - 'USER': 'cltbld', 02:14:24 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:24 INFO - '_': '/tools/buildbot/bin/python'} 02:14:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:24 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 02:14:24 INFO - Cleaning up... 02:14:24 INFO - Return code: 0 02:14:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:14:24 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')]} 02:14:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:24 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')]} 02:14:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:24 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 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:24 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 02:14:24 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 02:14:24 INFO - Using env: {'DISPLAY': ':0', 02:14:24 INFO - 'HOME': '/home/cltbld', 02:14:24 INFO - 'LANG': 'en_US.UTF-8', 02:14:24 INFO - 'LANGUAGE': 'en_US:en', 02:14:24 INFO - 'LOGNAME': 'cltbld', 02:14:24 INFO - 'MAIL': '/var/mail/cltbld', 02:14:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:24 INFO - 'MOZ_NO_REMOTE': '1', 02:14:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:24 INFO - 'NO_EM_RESTART': '1', 02:14:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:24 INFO - 'PWD': '/builds/slave/test', 02:14:24 INFO - 'SHELL': '/bin/bash', 02:14:24 INFO - 'SHLVL': '1', 02:14:24 INFO - 'TERM': 'linux', 02:14:24 INFO - 'TMOUT': '86400', 02:14:24 INFO - 'USER': 'cltbld', 02:14:24 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:24 INFO - '_': '/tools/buildbot/bin/python'} 02:14:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:24 INFO - Downloading/unpacking psutil>=3.1.1 02:14:24 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 02:14:24 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 02:14:24 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 02:14:24 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 02:14:24 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 02:14:24 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 02:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:14:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:14:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:14:26 INFO - Installing collected packages: psutil 02:14:26 INFO - Running setup.py install for psutil 02:14:26 INFO - building 'psutil._psutil_linux' extension 02:14:26 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 02:14:26 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 02:14:26 INFO - building 'psutil._psutil_posix' extension 02:14:26 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 02:14:26 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 02:14:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:14:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:14:27 INFO - Successfully installed psutil 02:14:27 INFO - Cleaning up... 02:14:27 INFO - Return code: 0 02:14:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:14:27 INFO - Using env: {'DISPLAY': ':0', 02:14:27 INFO - 'HOME': '/home/cltbld', 02:14:27 INFO - 'LANG': 'en_US.UTF-8', 02:14:27 INFO - 'LANGUAGE': 'en_US:en', 02:14:27 INFO - 'LOGNAME': 'cltbld', 02:14:27 INFO - 'MAIL': '/var/mail/cltbld', 02:14:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:27 INFO - 'MOZ_NO_REMOTE': '1', 02:14:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:27 INFO - 'NO_EM_RESTART': '1', 02:14:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:27 INFO - 'PWD': '/builds/slave/test', 02:14:27 INFO - 'SHELL': '/bin/bash', 02:14:27 INFO - 'SHLVL': '1', 02:14:27 INFO - 'TERM': 'linux', 02:14:27 INFO - 'TMOUT': '86400', 02:14:27 INFO - 'USER': 'cltbld', 02:14:27 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:27 INFO - '_': '/tools/buildbot/bin/python'} 02:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:14:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:14:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:14:29 INFO - Installing collected packages: mozsystemmonitor 02:14:29 INFO - Running setup.py install for mozsystemmonitor 02:14:29 INFO - Successfully installed mozsystemmonitor 02:14:29 INFO - Cleaning up... 02:14:29 INFO - Return code: 0 02:14:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:14:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:14:29 INFO - Using env: {'DISPLAY': ':0', 02:14:29 INFO - 'HOME': '/home/cltbld', 02:14:29 INFO - 'LANG': 'en_US.UTF-8', 02:14:29 INFO - 'LANGUAGE': 'en_US:en', 02:14:29 INFO - 'LOGNAME': 'cltbld', 02:14:29 INFO - 'MAIL': '/var/mail/cltbld', 02:14:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:29 INFO - 'MOZ_NO_REMOTE': '1', 02:14:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:29 INFO - 'NO_EM_RESTART': '1', 02:14:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:29 INFO - 'PWD': '/builds/slave/test', 02:14:29 INFO - 'SHELL': '/bin/bash', 02:14:29 INFO - 'SHLVL': '1', 02:14:29 INFO - 'TERM': 'linux', 02:14:29 INFO - 'TMOUT': '86400', 02:14:29 INFO - 'USER': 'cltbld', 02:14:29 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:29 INFO - '_': '/tools/buildbot/bin/python'} 02:14:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:29 INFO - Downloading/unpacking jsonschema==2.5.1 02:14:29 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 02:14:29 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 02:14:29 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 02:14:29 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 02:14:29 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 02:14:29 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 02:14:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:14:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:31 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 02:14:31 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 02:14:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:32 INFO - Downloading functools32-3.2.3-2.tar.gz 02:14:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:14:32 INFO - warning: no files found matching '*.txt' 02:14:32 INFO - no previously-included directories found matching 'build' 02:14:32 INFO - no previously-included directories found matching 'dist' 02:14:32 INFO - no previously-included directories found matching '.git*' 02:14:32 INFO - Installing collected packages: jsonschema, functools32 02:14:32 INFO - Running setup.py install for functools32 02:14:32 INFO - warning: no files found matching '*.txt' 02:14:32 INFO - no previously-included directories found matching 'build' 02:14:32 INFO - no previously-included directories found matching 'dist' 02:14:32 INFO - no previously-included directories found matching '.git*' 02:14:32 INFO - Successfully installed jsonschema functools32 02:14:32 INFO - Cleaning up... 02:14:32 INFO - Return code: 0 02:14:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:14:32 INFO - Using env: {'DISPLAY': ':0', 02:14:32 INFO - 'HOME': '/home/cltbld', 02:14:32 INFO - 'LANG': 'en_US.UTF-8', 02:14:32 INFO - 'LANGUAGE': 'en_US:en', 02:14:32 INFO - 'LOGNAME': 'cltbld', 02:14:32 INFO - 'MAIL': '/var/mail/cltbld', 02:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:32 INFO - 'MOZ_NO_REMOTE': '1', 02:14:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:32 INFO - 'NO_EM_RESTART': '1', 02:14:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:32 INFO - 'PWD': '/builds/slave/test', 02:14:32 INFO - 'SHELL': '/bin/bash', 02:14:32 INFO - 'SHLVL': '1', 02:14:32 INFO - 'TERM': 'linux', 02:14:32 INFO - 'TMOUT': '86400', 02:14:32 INFO - 'USER': 'cltbld', 02:14:32 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:32 INFO - '_': '/tools/buildbot/bin/python'} 02:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:14:32 INFO - Cleaning up... 02:14:32 INFO - Return code: 0 02:14:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:14:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:14:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:14:32 INFO - Using env: {'DISPLAY': ':0', 02:14:32 INFO - 'HOME': '/home/cltbld', 02:14:32 INFO - 'LANG': 'en_US.UTF-8', 02:14:32 INFO - 'LANGUAGE': 'en_US:en', 02:14:32 INFO - 'LOGNAME': 'cltbld', 02:14:32 INFO - 'MAIL': '/var/mail/cltbld', 02:14:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:32 INFO - 'MOZ_NO_REMOTE': '1', 02:14:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:32 INFO - 'NO_EM_RESTART': '1', 02:14:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:32 INFO - 'PWD': '/builds/slave/test', 02:14:32 INFO - 'SHELL': '/bin/bash', 02:14:32 INFO - 'SHLVL': '1', 02:14:32 INFO - 'TERM': 'linux', 02:14:32 INFO - 'TMOUT': '86400', 02:14:32 INFO - 'USER': 'cltbld', 02:14:32 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:32 INFO - '_': '/tools/buildbot/bin/python'} 02:14:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:32 INFO - Downloading/unpacking blobuploader==1.2.4 02:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:34 INFO - Downloading blobuploader-1.2.4.tar.gz 02:14:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:14:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:14:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:14:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:35 INFO - Downloading docopt-0.6.1.tar.gz 02:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:14:35 INFO - Installing collected packages: blobuploader, requests, docopt 02:14:35 INFO - Running setup.py install for blobuploader 02:14:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:14:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:14:35 INFO - Running setup.py install for requests 02:14:36 INFO - Running setup.py install for docopt 02:14:36 INFO - Successfully installed blobuploader requests docopt 02:14:36 INFO - Cleaning up... 02:14:36 INFO - Return code: 0 02:14:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:36 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')]} 02:14:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:36 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')]} 02:14:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:36 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 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:36 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 02:14:36 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 02:14:36 INFO - Using env: {'DISPLAY': ':0', 02:14:36 INFO - 'HOME': '/home/cltbld', 02:14:36 INFO - 'LANG': 'en_US.UTF-8', 02:14:36 INFO - 'LANGUAGE': 'en_US:en', 02:14:36 INFO - 'LOGNAME': 'cltbld', 02:14:36 INFO - 'MAIL': '/var/mail/cltbld', 02:14:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:36 INFO - 'MOZ_NO_REMOTE': '1', 02:14:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:36 INFO - 'NO_EM_RESTART': '1', 02:14:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:36 INFO - 'PWD': '/builds/slave/test', 02:14:36 INFO - 'SHELL': '/bin/bash', 02:14:36 INFO - 'SHLVL': '1', 02:14:36 INFO - 'TERM': 'linux', 02:14:36 INFO - 'TMOUT': '86400', 02:14:36 INFO - 'USER': 'cltbld', 02:14:36 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:36 INFO - '_': '/tools/buildbot/bin/python'} 02:14:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:14:36 INFO - Running setup.py (path:/tmp/pip-3NWS72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:14:36 INFO - Running setup.py (path:/tmp/pip-dqEfXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:14:36 INFO - Running setup.py (path:/tmp/pip-gkJzM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:14:36 INFO - Running setup.py (path:/tmp/pip-L9Rk6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:14:36 INFO - Running setup.py (path:/tmp/pip-RYkEZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:14:36 INFO - Running setup.py (path:/tmp/pip-qwnVNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:14:36 INFO - Running setup.py (path:/tmp/pip-LbOuPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:14:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:14:36 INFO - Running setup.py (path:/tmp/pip-BekL_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:14:37 INFO - Running setup.py (path:/tmp/pip-AcigD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:14:37 INFO - Running setup.py (path:/tmp/pip-mrQfb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:14:37 INFO - Running setup.py (path:/tmp/pip-hgqFxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:14:37 INFO - Running setup.py (path:/tmp/pip-2IJmsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:14:37 INFO - Running setup.py (path:/tmp/pip-LaSh9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:14:37 INFO - Running setup.py (path:/tmp/pip-ShZ9HH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:37 INFO - Running setup.py (path:/tmp/pip-xJslwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:14:37 INFO - Running setup.py (path:/tmp/pip-72l03N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:14:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:14:37 INFO - Running setup.py (path:/tmp/pip-wBeTgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:14:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:14:37 INFO - Running setup.py install for manifestparser 02:14:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:14:37 INFO - Running setup.py install for mozcrash 02:14:37 INFO - Running setup.py install for mozdebug 02:14:37 INFO - Running setup.py install for mozdevice 02:14:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Running setup.py install for mozfile 02:14:38 INFO - Running setup.py install for mozhttpd 02:14:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Running setup.py install for mozinfo 02:14:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Running setup.py install for mozInstall 02:14:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Running setup.py install for mozleak 02:14:38 INFO - Running setup.py install for mozlog 02:14:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Running setup.py install for moznetwork 02:14:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:14:38 INFO - Running setup.py install for mozprocess 02:14:38 INFO - Running setup.py install for mozprofile 02:14:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:14:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:14:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:14:39 INFO - Running setup.py install for mozrunner 02:14:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:14:39 INFO - Running setup.py install for mozscreenshot 02:14:39 INFO - Running setup.py install for moztest 02:14:39 INFO - Running setup.py install for mozversion 02:14:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:14:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:14:39 INFO - Cleaning up... 02:14:39 INFO - Return code: 0 02:14:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:39 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')]} 02:14:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:39 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')]} 02:14:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:39 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 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:39 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 02:14:39 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 02:14:39 INFO - Using env: {'DISPLAY': ':0', 02:14:39 INFO - 'HOME': '/home/cltbld', 02:14:39 INFO - 'LANG': 'en_US.UTF-8', 02:14:39 INFO - 'LANGUAGE': 'en_US:en', 02:14:39 INFO - 'LOGNAME': 'cltbld', 02:14:39 INFO - 'MAIL': '/var/mail/cltbld', 02:14:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:39 INFO - 'MOZ_NO_REMOTE': '1', 02:14:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:39 INFO - 'NO_EM_RESTART': '1', 02:14:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:39 INFO - 'PWD': '/builds/slave/test', 02:14:39 INFO - 'SHELL': '/bin/bash', 02:14:39 INFO - 'SHLVL': '1', 02:14:39 INFO - 'TERM': 'linux', 02:14:39 INFO - 'TMOUT': '86400', 02:14:39 INFO - 'USER': 'cltbld', 02:14:39 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:39 INFO - '_': '/tools/buildbot/bin/python'} 02:14:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:14:39 INFO - Running setup.py (path:/tmp/pip-00xEMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:14:39 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)) 02:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:14:39 INFO - Running setup.py (path:/tmp/pip-MDjhq5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:14:39 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)) 02:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:14:39 INFO - Running setup.py (path:/tmp/pip-Uu59tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:14:39 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)) 02:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:14:39 INFO - Running setup.py (path:/tmp/pip-5xgDNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:14:40 INFO - Running setup.py (path:/tmp/pip-vsXIlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:14:40 INFO - Running setup.py (path:/tmp/pip-h5SjWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:14:40 INFO - Running setup.py (path:/tmp/pip-e_JDUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:14:40 INFO - Running setup.py (path:/tmp/pip-sQs2LX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:14:40 INFO - Running setup.py (path:/tmp/pip-RZx_aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:14:40 INFO - Running setup.py (path:/tmp/pip-fZ50V_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:14:40 INFO - Running setup.py (path:/tmp/pip-MbKNB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:14:40 INFO - Running setup.py (path:/tmp/pip-XIXx8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:14:40 INFO - Running setup.py (path:/tmp/pip-sVLoZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:14:40 INFO - Running setup.py (path:/tmp/pip-yRu0vD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:40 INFO - Running setup.py (path:/tmp/pip-YB1Zrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:14:40 INFO - Running setup.py (path:/tmp/pip-hRcIre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:14:40 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)) 02:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:14:40 INFO - Running setup.py (path:/tmp/pip-69gm7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:14:40 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)) 02:14:40 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)) 02:14:40 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)) 02:14:40 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)) 02:14:40 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)) 02:14:40 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)) 02:14:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:14:40 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 02:14:40 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 02:14:40 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 02:14:40 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 02:14:40 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 02:14:40 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 02:14:42 INFO - Downloading blessings-1.6.tar.gz 02:14:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:14:43 INFO - Installing collected packages: blessings 02:14:43 INFO - Running setup.py install for blessings 02:14:43 INFO - Successfully installed blessings 02:14:43 INFO - Cleaning up... 02:14:43 INFO - Return code: 0 02:14:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:14:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:14:43 INFO - Reading from file tmpfile_stdout 02:14:43 INFO - Current package versions: 02:14:43 INFO - argparse == 1.2.1 02:14:43 INFO - blessings == 1.6 02:14:43 INFO - blobuploader == 1.2.4 02:14:43 INFO - docopt == 0.6.1 02:14:43 INFO - functools32 == 3.2.3-2 02:14:43 INFO - jsonschema == 2.5.1 02:14:43 INFO - manifestparser == 1.1 02:14:43 INFO - mozInstall == 1.12 02:14:43 INFO - mozcrash == 1.0 02:14:43 INFO - mozdebug == 0.1 02:14:43 INFO - mozdevice == 0.49 02:14:43 INFO - mozfile == 1.2 02:14:43 INFO - mozhttpd == 0.7 02:14:43 INFO - mozinfo == 0.9 02:14:43 INFO - mozleak == 0.1 02:14:43 INFO - mozlog == 3.4 02:14:43 INFO - moznetwork == 0.27 02:14:43 INFO - mozprocess == 0.25 02:14:43 INFO - mozprofile == 0.28 02:14:43 INFO - mozrunner == 6.13 02:14:43 INFO - mozscreenshot == 0.1 02:14:43 INFO - mozsystemmonitor == 0.3 02:14:43 INFO - moztest == 0.8 02:14:43 INFO - mozversion == 1.4 02:14:43 INFO - psutil == 3.1.1 02:14:43 INFO - requests == 1.2.3 02:14:43 INFO - wsgiref == 0.1.2 02:14:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:43 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')]} 02:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:43 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')]} 02:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:43 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 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:43 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 02:14:43 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 02:14:43 INFO - Using env: {'DISPLAY': ':0', 02:14:43 INFO - 'HOME': '/home/cltbld', 02:14:43 INFO - 'LANG': 'en_US.UTF-8', 02:14:43 INFO - 'LANGUAGE': 'en_US:en', 02:14:43 INFO - 'LOGNAME': 'cltbld', 02:14:43 INFO - 'MAIL': '/var/mail/cltbld', 02:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:43 INFO - 'MOZ_NO_REMOTE': '1', 02:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:43 INFO - 'NO_EM_RESTART': '1', 02:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:43 INFO - 'PWD': '/builds/slave/test', 02:14:43 INFO - 'SHELL': '/bin/bash', 02:14:43 INFO - 'SHLVL': '1', 02:14:43 INFO - 'TERM': 'linux', 02:14:43 INFO - 'TMOUT': '86400', 02:14:43 INFO - 'USER': 'cltbld', 02:14:43 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:43 INFO - '_': '/tools/buildbot/bin/python'} 02:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:43 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)) 02:14:43 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)) 02:14:43 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)) 02:14:43 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)) 02:14:43 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)) 02:14:43 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)) 02:14:43 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)) 02:14:43 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)) 02:14:43 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)) 02:14:43 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)) 02:14:43 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)) 02:14:43 INFO - Cleaning up... 02:14:43 INFO - Return code: 0 02:14:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:14:43 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')]} 02:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:43 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')]} 02:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:14:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0xb3b1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xb79a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdd9310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, '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-1493021468.957087-1264642343', '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 02:14:43 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 02:14:43 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 02:14:43 INFO - Using env: {'DISPLAY': ':0', 02:14:43 INFO - 'HOME': '/home/cltbld', 02:14:43 INFO - 'LANG': 'en_US.UTF-8', 02:14:43 INFO - 'LANGUAGE': 'en_US:en', 02:14:43 INFO - 'LOGNAME': 'cltbld', 02:14:43 INFO - 'MAIL': '/var/mail/cltbld', 02:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:43 INFO - 'MOZ_NO_REMOTE': '1', 02:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:14:43 INFO - 'NO_EM_RESTART': '1', 02:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:43 INFO - 'PWD': '/builds/slave/test', 02:14:43 INFO - 'SHELL': '/bin/bash', 02:14:43 INFO - 'SHLVL': '1', 02:14:43 INFO - 'TERM': 'linux', 02:14:43 INFO - 'TMOUT': '86400', 02:14:43 INFO - 'USER': 'cltbld', 02:14:43 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:43 INFO - '_': '/tools/buildbot/bin/python'} 02:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:14:43 INFO - Cleaning up... 02:14:43 INFO - Return code: 0 02:14:43 INFO - Running post-action listener: _resource_record_post_action 02:14:43 INFO - Running post-action listener: _start_resource_monitoring 02:14:43 INFO - Starting resource monitoring. 02:14:43 INFO - [mozharness: 2017-04-24 09:14:43.939645Z] Finished create-virtualenv step (success) 02:14:43 INFO - [mozharness: 2017-04-24 09:14:43.940116Z] Running install step. 02:14:43 INFO - Running pre-action listener: _resource_record_pre_action 02:14:43 INFO - Running main action method: install 02:14:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:14:44 INFO - Reading from file tmpfile_stdout 02:14:44 INFO - Detecting whether we're running mozinstall >=1.0... 02:14:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:14:44 INFO - Reading from file tmpfile_stdout 02:14:44 INFO - Output received: 02:14:44 INFO - Usage: mozinstall [options] installer 02:14:44 INFO - Options: 02:14:44 INFO - -h, --help show this help message and exit 02:14:44 INFO - -d DEST, --destination=DEST 02:14:44 INFO - Directory to install application into. [default: 02:14:44 INFO - "/builds/slave/test"] 02:14:44 INFO - --app=APP Application being installed. [default: firefox] 02:14:44 INFO - mkdir: /builds/slave/test/build/application 02:14:44 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'] 02:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:15:00 INFO - Reading from file tmpfile_stdout 02:15:00 INFO - Output received: 02:15:00 INFO - /builds/slave/test/build/application/firefox/firefox 02:15:00 INFO - Running post-action listener: _resource_record_post_action 02:15:00 INFO - [mozharness: 2017-04-24 09:15:00.869898Z] Finished install step (success) 02:15:00 INFO - [mozharness: 2017-04-24 09:15:00.870172Z] Running run-tests step. 02:15:00 INFO - Running pre-action listener: _resource_record_pre_action 02:15:00 INFO - Running main action method: run_tests 02:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:15:00 INFO - Python 2.7.3 02:15:00 INFO - Return code: 0 02:15:00 INFO - grabbing minidump binary from tooltool 02:15:00 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')]} 02:15:00 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 0xdd6400>, '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 0xdd73b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xdd7840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:15:00 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 02:15:00 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 02:15:00 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 02:15:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:15:00 INFO - Return code: 0 02:15:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:15:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:15:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:15:00 INFO - ENV: RUST_BACKTRACE is now 1 02:15:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:15:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/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 02:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/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 02:15:00 INFO - Using env: {'DISPLAY': ':0', 02:15:00 INFO - 'HOME': '/home/cltbld', 02:15:00 INFO - 'LANG': 'en_US.UTF-8', 02:15:00 INFO - 'LANGUAGE': 'en_US:en', 02:15:00 INFO - 'LOGNAME': 'cltbld', 02:15:00 INFO - 'MAIL': '/var/mail/cltbld', 02:15:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:15:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:15:00 INFO - 'MOZ_NO_REMOTE': '1', 02:15:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:15:00 INFO - 'NO_EM_RESTART': '1', 02:15:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:15:00 INFO - 'PWD': '/builds/slave/test', 02:15:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:15:00 INFO - 'RUST_BACKTRACE': '1', 02:15:00 INFO - 'SHELL': '/bin/bash', 02:15:00 INFO - 'SHLVL': '1', 02:15:00 INFO - 'TERM': 'linux', 02:15:00 INFO - 'TMOUT': '86400', 02:15:00 INFO - 'USER': 'cltbld', 02:15:00 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1493021468.957087-1264642343', 02:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:15:00 INFO - '_': '/tools/buildbot/bin/python'} 02:15:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/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 02:15:01 INFO - mozversion application_buildid: 20170424074707 02:15:01 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:15:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:15:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:15:01 INFO - mozversion application_name: Firefox 02:15:01 INFO - mozversion application_remotingname: firefox-dev 02:15:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:15:01 INFO - mozversion application_vendor: Mozilla 02:15:01 INFO - mozversion application_version: 54.0a2 02:15:01 INFO - mozversion platform_buildid: 20170424074707 02:15:01 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 02:15:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:15:01 INFO - mozversion platform_version: 54.0a2 02:15:01 INFO - using testdate: 1493025301 02:15:01 INFO - actual date: 1493025301 02:15:01 INFO - starting webserver on 'localhost:38796' 02:15:01 INFO - SUITE-START | Running 2 tests 02:15:01 INFO - TEST-START | dromaeo_css 02:15:01 INFO - operating with platform_type : linux_ 02:15:01 INFO - Initialising browser for dromaeo_css test... 02:15:01 INFO - TEST-INFO | started process 27349 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkDdmPl/profile http://localhost:38796/getInfo.html) 02:15:05 INFO - PROCESS | 27349 | __metrics Screen width/height:1600/1200 02:15:05 INFO - PROCESS | 27349 | colorDepth:24 02:15:05 INFO - PROCESS | 27349 | Browser inner width/height: 1024/697 02:15:05 INFO - PROCESS | 27349 | __metrics 02:15:06 INFO - PROCESS | 27349 | 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" 02:15:06 INFO - PROCESS | 27349 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:15:07 INFO - TEST-INFO | 27349: exit 0 02:15:07 INFO - Browser initialized. 02:15:07 INFO - Running cycle 1/1 for dromaeo_css test... 02:15:07 INFO - TEST-INFO | started process 27444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkDdmPl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:15:19 INFO - PROCESS | 27444 | RSS: Main: 139919360 02:15:19 INFO - PROCESS | 27444 | 02:18:20 INFO - PROCESS | 27444 | Cycle 1(1): loaded http://localhost:38796/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38796/tests/dromaeo/cssquery-ext.html) 02:18:22 INFO - PROCESS | 27444 | RSS: Main: 217886720 02:18:22 INFO - PROCESS | 27444 | 02:20:08 INFO - PROCESS | 27444 | ************************* 02:20:08 INFO - PROCESS | 27444 | A coding exception was thrown and uncaught in a Task. 02:20:08 INFO - PROCESS | 27444 | 02:20:08 ERROR - PROCESS | 27444 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:20:08 INFO - PROCESS | 27444 | Full stack: 02:20:08 INFO - PROCESS | 27444 | ************************* 02:20:08 INFO - PROCESS | 27444 | ************************* 02:20:08 INFO - PROCESS | 27444 | A coding exception was thrown and uncaught in a Task. 02:20:08 INFO - PROCESS | 27444 | 02:20:08 ERROR - PROCESS | 27444 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:20:08 INFO - PROCESS | 27444 | Full stack: 02:20:08 INFO - PROCESS | 27444 | ************************* 02:20:08 INFO - PROCESS | 27444 | ************************* 02:20:08 INFO - PROCESS | 27444 | A coding exception was thrown and uncaught in a Task. 02:20:08 INFO - PROCESS | 27444 | 02:20:08 ERROR - PROCESS | 27444 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:20:08 INFO - PROCESS | 27444 | Full stack: 02:20:08 INFO - PROCESS | 27444 | ************************* 02:20:08 INFO - PROCESS | 27444 | ************************* 02:20:08 INFO - PROCESS | 27444 | A coding exception was thrown and uncaught in a Task. 02:20:08 INFO - PROCESS | 27444 | 02:20:08 ERROR - PROCESS | 27444 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:20:08 INFO - PROCESS | 27444 | Full stack: 02:20:08 INFO - PROCESS | 27444 | ************************* 02:21:24 INFO - PROCESS | 27444 | Cycle 1(1): loaded http://localhost:38796/tests/dromaeo/cssquery-ext.html (next: http://localhost:38796/tests/dromaeo/cssquery-jquery.html) 02:21:25 INFO - PROCESS | 27444 | RSS: Main: 221114368 02:21:25 INFO - PROCESS | 27444 | 02:24:27 INFO - PROCESS | 27444 | Cycle 1(1): loaded http://localhost:38796/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38796/tests/dromaeo/cssquery-mootools.html) 02:24:27 INFO - PROCESS | 27444 | RSS: Main: 199348224 02:24:27 INFO - PROCESS | 27444 | 02:27:29 INFO - PROCESS | 27444 | Cycle 1(1): loaded http://localhost:38796/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38796/tests/dromaeo/cssquery-prototype.html) 02:27:30 INFO - PROCESS | 27444 | RSS: Main: 170643456 02:27:30 INFO - PROCESS | 27444 | 02:30:33 INFO - PROCESS | 27444 | Cycle 1(1): loaded http://localhost:38796/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38796/tests/dromaeo/cssquery-yui.html) 02:30:33 INFO - PROCESS | 27444 | RSS: Main: 177549312 02:30:33 INFO - PROCESS | 27444 | 02:33:36 INFO - PROCESS | 27444 | Cycle 1(1): loaded http://localhost:38796/tests/dromaeo/cssquery-yui.html (next: http://localhost:38796/tests/dromaeo/cssquery-dojo.html) 02:33:36 INFO - PROCESS | 27444 | RSS: Main: 260476928 02:33:36 INFO - PROCESS | 27444 | 02:33:36 INFO - PROCESS | 27444 | __start_tp_report 02:33:36 INFO - PROCESS | 27444 | _x_x_mozilla_page_load 02:33:36 INFO - PROCESS | 27444 | _x_x_mozilla_page_load_details 02:33:36 INFO - PROCESS | 27444 | |i|pagename|runs| 02:33:36 INFO - PROCESS | 27444 | |0;dojo.html;3357.647447018063;3364.6635336466356;3401.0647072055167;3412.1981334386433;3456.7925924444535;5653.349864765553;5709.257796486456;5722.771089156434;5723.942760572393;5742.167385729077;5688.800891968782;5709.172170035343;5735.713214339287;5747.511461525775;5748.338941021784;5193.417076642615;5227.372715274172;5241.8951620967555;5247.055530004598;5279.472052794728;5094.46508116648;5292.311999440068;5300.602454815885;5307.973460132694;5331.866703332428;5120.513551212647;5122.129238029525;5156.767945442464;5227.137522308814;5236.528712415882;5194.818181363653;5245.527902488794;5246.213068039787;5246.606504512177;5248.8425347239645;5120.359955005624;5160.767765450564;5235.502627250406;5241.213817927303;5266.683998960037;5473.945260547383;5496.4503549645115;5608.719564021823;5654.180143879114;5686.829395118111;6112.76377709902;6131.480086409457;6145.432914606791;6151.6001459904965;6153.261608606951;6213.285472170713;6213.906791398133;6214.720337584819;6221.035739460391;6225.844353891143;5549.805756798533;5585.888282234373;5702.429757024347;5746.540276777848;5766.538676905838;6275.121482992385;6288.874222515616;6289.465395441347;6289.528285378615;6293.811185664437;5955.880882382328;6036.094585812163;6059.091136329498;6061.302950160788;6075.75696972125;5922.8815423691285;5941.287045554474;6008.309044459943;6130.448259656563;6138.815835524942;6098.268207815002;6100.633961962297;6118.60229085108;6133.539984501181;6143.170671959318;5835.708214589338;5869.618474799125;5869.765209391577;6069.484093852072;6089.117078023662;4610.561996610384;4622.375979242826;4646.860594182112;4657.184992626277;4681.50844161365;4083.734557253769;4170.416141740159;4195.958040419618;4211.263028969918;4213.136307057046;5817.88364232713;5849.736761845642;5881.117832325185;5965.492933100735;5978.910316345258;6165.075238714139;6193.907091393633;6198.318184999646;6198.349173336825;6199.194104766441;5925.34821169671;5935.910961335583;5937.346891841894;5943.257092863392;5968.582199246098;5023;5038.571721403649;5103.157978933508;5118.155504341817;5201.765920533585;6182.876342473125;6227.657478838707;6239.594426362272;6242.2509298883515;6321.841953951188;6153.353897840662;6191.7523299069335;6204.472619827366;6205.069239614094;6208.410201030895;6391.265004524539;6405.423511883766;6447.6453795041725;6450.290468048604;6451.032345148265;5862.94137058641;5877.911831322619;6075.1798507200965;6076.696165191811;6091.573589848668;6038.698065096815;6043.848903777441;6115.727150604437;6126.27339965311;6128.014278093809;5888.499477544457;5961.4038596140035;5961.986462301333;5965.015772397506;5995.430434108753;5828.067509198508;5944.6433214005565;5955.076963070731;5957.046872500379;5960.403959604005;5888.234529511136;5893.410658934244;5902.852428689317;5989.431004054608;6013.849653758691;5821.854453638693;5857.384974577608;5863.648181109147;5964.731587078679;6006.3693312201585;5892.94107058924;5900.822975310747;5938.198343223609;5938.435848594377;5971.044632858721 02:33:36 INFO - PROCESS | 27444 | |1;ext.html;784.4508843809335;795.6578671171394;796.1083586383252;835.7785186925464;964.8890377606577;39854.20291594166;40679.59320406795;44315;44344.77827610858;44438.555614443845;39553.802230988804;39768.40347394792;39805.601943980626;39823.203535929264;39850.60149398505;7150.10623672041;7364.963175184116;7842.058952925643;7952.324052455549;7955.522668639871;10620.681379558588;10790.406527640976;10838.132949364031;10838.837417438705;11202.327860328365;11027.17296202784;11061.944690276578;11067.557297708123;11072;11073.114150867912;10847.620333288376;10879.891201087967;10914.781704365947;10926.890731092706;10944.069754070913;10608.787824243473;10639.946800266027;10671.039606435457;10674.573017079307;10699.411532365717;42565.787171063945;42641.36037959402;42838.78580607108;42839.357409638884;42848.78575607133;4192.24539582875;4234.894127646803;4269.914601707979;4356.281213599752;4365.43249377582;29951.251218719488;30217.395652087056;30318.393632127456;30422.54366184509;31617.73529058835;4286.807093680792;4384.934225986613;4390.604845563883;4392.714473559208;4490.865274041784;226564;227386;228797;229729.85135074385;229776;38110.23779524394;38742.61257387447;51854;55536.44463555393;55776.72111639497;57918.420815792146;57952.420475795545;58156.709216453644;58289.41710582925;58557;16869.91565042167;16936.661266774594;16960.57598560021;16967.91516042412;17006.57483562921;10438.164946804238;10610.681679549625;14623.488177913721;15046.097234165985;15099.622509437351;14125.505607303696;14132.364043618061;14180.078294910798;14274.785878211835;14355.78466323006;5615.719214039282;5778.3643799182055;5831.620944638585;5835.416458354131;5916.1421593868645;1016.3929520779178;1024.2676486312323;1032.9431881246603;1057.5188289328337;1069.3797597393493;28864;29044.419111618074;29508.557371639443;29872.40255194884;29878.10365689033;1074.5110974506738;1106.7841770854768;1114.0753174864842;1120.4453795371344;1130.463030060731;14597.562073137822;15528.14595197275;15533.766993495106;15594.766078508832;15744.370225190863;302536;303019;305708;306608;308066;57371.426285736605;57400.42599574034;57640.42359576435;57688.71155644279;57944;1470.867621914033;1508.1931166825914;1525.6491007068214;1541.9845801542065;1545.2505534815605;26031.869840650674;27308.634568271904;27619.171424857286;27745.722542775122;27790;43316;43461.782691086344;43466.565334346255;43534.12931741411;43559.56440435555;278931;279265;279626;280537;281362;27077.72922270752;27610;34806.65193348136;35568.644313556535;36280.09299767527;44884.551154488036;45631;46135.53864461447;46315;46414;33348;34045.65954340425;34283;34415.483767743004;34674.653253468154;33569.992900212055;33675.83162084174;33851.83074084614;33893.661063390035;34784.130396740286;39444.80277598594;39866.80066599648;39984.4002339971;40004;40460;32240.387980601346;32798.01605951825;32827.67172328246;32862.83568582142;33203.50194747129;25140.24579262625;26153.346166345993;26442.735572644797;26534.867325663246;26820.59769103426 02:33:36 INFO - PROCESS | 27444 | |2;jquery.html;427.6514640567939;434.15556742136545;438.314038529701;439.92301347264225;443.4523363645899;19595.412137635874;19636.116374763133;21451.463713407134;21642.24252151175;21743.565128697417;20460.07929643166;21511.247106351282;22106.668399974013;22122.33632991009;22515;6694.230163531195;6885.586864788129;6895.069165662647;6898.896516552256;6901;6487.708053137597;6620.172478440195;6673.032410300503;6696.531242813005;6702.061711360413;6453.903191452108;6635.8009259722285;6663.400293973541;6716.992451132321;6717.16035495563;6684.131062961808;6703.698333575001;6710.865782684319;6713.865722685568;6714.6306953117555;6443.516736244754;6633.800985970429;6692.23039350476;6699.095622091004;6705.664716764142;23945.521089578284;24909.750902491105;25104;25302.493950120894;25330.87334563334;3164.699353561408;3279.5572597699243;3325.3681800458075;3353.8658453661824;3359.160209947513;15580.143092129929;16158.838411615969;16459.341626335052;16721.247543860554;17076.31694732209;2182.4216582605623;2238.0488292475634;2275.8293128015466;2276.8064714499287;2323.035940084863;112328;114892.42553787178;115114;115489;115528.42235788851;17657.646847063243;17902;18114.456566302768;18421.26314947387;18726.344577939708;32181;32369.35261294761;32421.51367729486;32499.837500812344;32573.348533029206;10681.519331630096;10886.564537418415;10894.237403381687;10921;11166.553337866557;9117;9129.452232865917;9160.267178625694;9229.815403692022;9362.765930851783;9149.862752058725;9177.338571814233;9363.391379560308;9508.286878484141;9545.570449329796;3835.6355796870075;3949.5852935441367;3986.920261594752;4019.070845993881;4038.4749982502085;933.7245512573807;950.5674917912334;962.8748262725801;963.8361478548663;970.6893793985998;20074.598508029758;21542.569148617255;21632;21700.34898953034;21848.235311764012;964.2286171063122;968.3608818179968;978.8678528398717;980.4852452462515;985.7239972807625;11180.161487888277;11886.227395219283;12035.638930832089;12059.638210853689;12083.637490875288;582675;583317;622769;649535;650373;106414;109207.45396272968;109809;110288.4485577583;110821.99999999838;1229.557359350633;1237.4926280225066;1246.3643541793751;1259.0808709641829;1275.2922128218927;18292.45122646323;18511.166997484877;18691.532711682317;19011.486459537908;19118.522036949187;48822.755886220344;49037.509624903294;49450.75274623604;50089;50749.74625126851;63507.36492635014;66407.33592664011;67622.32377676161;67802.66098669475;68867.65566172136;12358.196717213352;12629.368531573567;12682.619521414372;12685.4291556882;12951.481940722642;13487.190768553919;13548.119372240773;13602.047856649939;13634.977376696588;13758.174509529461;12293.754124917272;12387.380630968595;12447.253164810141;12475.313857737912;12499;10892.564297428331;10996.670099897015;11020.669379918616;11254.987051165434;11394;11787.174897757075;11833.290002599872;12013.219140755822;12029;12095.57665481704;12037.337946413032;12087.274763513866;12156.270623762603;12204.206726562745;12491.625251242114;11918.344491053076;12021.338826364634;12255.653649749098;12368.628941131781;12571.434285457359 02:33:36 INFO - PROCESS | 27444 | |3;mootools.html;1132.5300000499792;1562.39066763962;1602.8557429831317;1607.6865011322793;1608.7828143200672;8247.793805154874;11168.162387820916;11323.886761132377;11839.644810655673;11867.525298988052;11575.189736718434;11625.418729063555;11625.883741162586;11708.824367634472;11727.941360293207;1935.4000259919399;2429.0283886445477;2461.5323088613195;2467.481828815951;2470.654108424813;2000.5398758285603;2032.4918770307422;2033.4916270932267;2035.4300795777206;2349.1777877742825;2406;2417.5164967006667;2419.6249581314923;2420.2860156253946;2420.5521978434044;2290.7709229077127;2305.147095574648;2312.213847291912;2313.410080429488;2322.988385058081;2269.5460907818338;2290.358699564125;2294.1167650454527;2298.0348253733473;2302.481941563152;11242.213045086848;11330.603428880033;11336.659900203007;11361.14791390649;11383.430828458626;5786.942130578683;5903.9668058089665;5905.202797622309;5942.227510423617;5942.37605051472;3420.863165473378;3470.4794280858073;3509.438489841639;3534.5935217450065;3577.821108944542;1309.8428188617331;1312.59309614019;1333.320006796531;1360.9115407498482;1368.835739711231;17097.48707538776;17277.91361043199;17308.567285817957;17312.394066207748;17316.134193290283;4779.808807647724;4914.729689867092;4930.137225985438;4967.850964471072;4973.204287314013;14156.29218539069;14177.716445671234;14178;14210.715785684228;14231.64420889486;5756.30924289088;5856.1508581255475;5865.941340586625;5877.5591830612875;5879.176915231871;5363.168708850135;5471.015217260851;5518.834434966958;5522;5526.336839579196;3312.6190488093694;3376.1728376547903;3947.50656167979;3979.2240513100246;4037.6366127048705;959.5825815770162;984.5077461269366;985.1675334342469;989.0554520432969;989.3766926836047;1020.9132223760915;1038.5170895533613;1048.533402635835;1050.8686414198226;1053.89461053894;7442.66508007141;7457.7016919323705;7544.396448284124;7560.508566943131;7745.148033716287;1111.4998250787016;1114.6656003199007;1125.5441546173813;1132.7916789128428;1142.4173671427466;4605.240135377692;4982.725950072709;4989.700617962933;4999.950000499948;5093.516115968977;13715.588532343847;14204.502842400467;14290;14312.212828294336;14394.424223030961;13768.93115534436;14046;14068.71862562743;14094.788578171336;14219.075760075562;10640.946795265974;10855.2401331906;10855.782884342269;10887.727806804893;10896.509657065451;7219.530730502501;7521.398288137046;7591.696332146873;7614.619269036637;7701;12710.872891270968;12869.678258043623;12899.677508062374;12967.870321297043;13164.078514503894;13139.408726607135;13182.077254592086;13229.867701323248;13230.470781168646;13487.595372138852;9803.264755143535;10111.140553053074;10137.797244055226;10139.138173255062;10141.036601522843;8256.876146857681;10766.246362754846;10895.128389728961;10919.344839309666;11103.944480277547;7526.811829704301;8908.643654253758;8929.374943753875;9034.412763170372;9107.726768196964;8823.514706691194;8990.100989900957;9033.864492032757;9133.041030691948;9175.862362064709;8550.786230344042;8585.785355366166;8620.827583448421;8686.174813392718;8745.60644770974;6516.609003459807;6592.967035164794;6609.20689517261;6665.700043498151;6676.365745254193;6667.633280169443;6779.15260592426;6802.8639427212165;6812.14848143982;6951.131108611424 02:33:36 INFO - PROCESS | 27444 | |4;prototype.html;385.2910644414278;398.7906349166688;400.3754143536084;414.5398607545622;422.4487044407048;5254.553362964148;5288.629795914288;5322;5342.759575819087;5353;5047.132560952453;5154.226865970107;5195.58435325174;5222.843314700555;5225.555827754636;4231.428757117796;4232.238197124517;4243.893902652443;4248.872533823975;4315.82736690532;4238.97880510597;4243.405923170759;4250.48994120706;4254.170436764816;4290.849820256291;4141;4174.24863524567;4207.873763787091;4230.619244268015;4252.957470425303;4177.621384942992;4184.456020717318;4187.309093999486;4193.97903010483;4206.768627725474;3827.2919509890758;3852.5569559500736;3877.8448862045484;3884.3979183226643;3902.4926759521363;4830.806767729287;5156.381234251876;5182.32629758129;5200.661956972823;5208.322918020633;5140.7172605506685;5148.304978827848;5158.9484105158845;5205.505476979719;5215.921761173586;5577.9401913636275;5590.385057643656;5613.831585052455;5646.769526037233;5660.886782264372;4401.845935392246;4635.582797548237;4712.835050773241;4745.525447455261;4746.09824133417;5439.809606663787;5579.553635709133;5802.158686990363;5827.504662103726;5839.503642190419;4475.619572336323;4475.731456112644;4484;4489.037235424604;4636.327732478771;4733.863872670534;4736.171170045228;4740.739259340769;4782.665213435096;4795.86583970137;4960.50394960501;5015.774290156952;5080.695158290514;5081.695098294114;5085.847424577269;5501.432091853802;5530.253415788898;5535.33575970878;5549.250851135126;5576.6375185613615;1666.4334126397084;1682.932682692703;1688.8178275207404;1689.5501522792001;1690.8900921440068;1244.6056354078426;1264.943077561512;1285.4279845468677;1296.0215037646706;1305.2494815481136;5312.963972025495;5385.892282154335;5401.891962160814;5478.890422191614;5574.86062848432;5729.5702822288495;5755.90637778829;5755.927865968248;5761.942380576224;5765.942340576625;5476.890462190813;5484.451554844483;5490.972545137249;5519.475649813261;5525.861853453695;2911.650601927782;2936.3980384021224;3009.172477568686;3010.6838781928022;3044.695530446982;5872.941270587325;5879.353271140171;5894.823155305347;5921.467067963904;5925.074350835996;5754.539636829044;5821.767129314865;5860.882782344329;5877.412258774174;5881.764729410861;5664.688442135722;5870.6204042050085;5893.789828814195;5898.423258067224;5903.852403689943;5662.5469962402085;5673.042800233913;5674.659520428788;5706.372299047019;5752.942470575241;5451.810690745958;5650.63270887391;5694.92135370595;5750.539956803529;5774.220480235283;5620.297462817148;5730.627509211888;5778.76389222037;5938.346781853907;5948.196993405835;4702.623790096715;4736.621070314298;4744.430668319824;4758.666893317434;4763.3807605011125;5496.230527726041;5648.858778530405;5702.6863522505;5751.577811202328;5766.250387449605;5561.582881284001;5562.860928476821;5566.554675626021;5566.860828479321;5573.860653483695;5504.6421982571;5513;5544.445555444423;5555.638868477249;5558.888822223452;5305.973470132624;5488.792465657549;5536.252605898313;5536.667799932017;5543.307086614173;5585.301837270341;5624.634398764068;5653.745581448762;5665.376808551138;5683.811732482941;5317.813876514303;5554.777808887599;5576.163575463713;5592.916106258491;5594.0769772987005 02:33:36 INFO - PROCESS | 27444 | |5;yui.html;829.991700082999;851.6380538271234;904.4347282948157;910.5492781073367;934.7149119518547;16948;18072.096395180222;18088.72866906994;18118.90940545297;18156.636867262663;12549.811752823714;16707.415240466587;16791.748123778154;16884.83115168848;16888.07115608644;894.3605322194629;923.5797712041006;926.1988380051274;926.8517037274038;926.9119433653792;915.5513798238849;921.5346250143701;923.6905636611743;925.2690374604055;925.6066171876959;956.3879117364892;960.0543464687278;960.2701946520659;960.4909398019026;960.7838236396825;949.1315446366566;950.5009869818366;950.7908260182749;951.1392190068;951.2770294576103;698.671624336561;699.0074094785417;699.0911814640947;699.5313140196081;699.8600279944031;16739.414120505724;16834.158292085347;16838.579035524086;16862.24119914607;16921.661566768595;17516.474505764847;17579.472615821545;17676.823231767645;17742.55643608907;18161;14133.293335333192;14178.71642567143;14209.740065730526;14218.786718199235;14573.489927852474;4097.242010228118;4166.437530933315;4188.141431006637;4265.850695225652;4323.1785960667685;54213;54948.725256373466;68708;70332.29667703308;72809.6359518199;14027.509037183854;14028;14073.225972571607;14305.64235894111;14342.067765595202;26370.47259054808;26582.06962756333;26607.46785064288;26630.467390652466;26642.467150657267;6150.07748837669;6236.875262494816;8698.565071746387;9098.363114582047;9204.493752843659;4796.424429068534;5091.9011490133;5119.8720031998755;5190.974045129826;5312.3890752563175;3755.7746535207975;3768.0768211788286;3805.904852378713;3825.6748176405317;3861.7296789225047;599.4365296621162;609.2810483629271;611.9204503414617;620.3610281410155;672.7510820996263;737.4321772235456;758.8823732321499;761.8285885675789;766.8657984852629;768.3814529303852;11746.412679365998;11944.573347504374;12074.698132546757;12271.202371845802;12341.876581234252;769.6651956398872;777.8677624803797;778.2645400096854;803.2168635580263;806.8507326144683;5546.528545073633;5632.183333416713;5705.885882282331;5706.896207385523;5806.767729290781;69682;70565;82068;82143.17856821355;87564;59737;59742.70128649416;61166;62052.37947620466;75663.62168189124;52144.73927630338;53761.46238537643;54489.1826622601;60591;62583.37416625775;11947.940260298643;12407.689807754878;12474.875251247371;12514.499420023098;12771.680707982376;37318.81340593279;37743;38745.2250954985;39083.02292442712;41360.58639413688;50799;51394.74302628463;52283;53485;56214.43785562092;14232.430702771773;14381.496647617283;14443.84449244079;14499;14558.781618275947;15606.9219653901;15632.2965466552;15651.139187344805;15885.126318052618;16287.185640718153;16407.17964101766;16459.341626335292;16609.833901661314;16755.832441675913;16884.324627014783;15754.291056902695;16323;16397.18014099314;16863.91568042152;16878.493645190665;14824.258787060387;15091.622709432351;16437.58906027359;16551.00693958366;16554.255058522154;10092.899071009197;10475.109615682752;10489.842652360374;10738.248322617654;10759.515821788194;11383.317000979967;12238.510459581517;12699.492020319083;12912.870871291167;13851.930740346637 02:33:36 INFO - PROCESS | 27444 | __end_tp_report 02:33:36 INFO - PROCESS | 27444 | __start_cc_report 02:33:36 INFO - PROCESS | 27444 | _x_x_mozilla_cycle_collect,1374 02:33:36 INFO - PROCESS | 27444 | __end_cc_report 02:33:36 INFO - PROCESS | 27444 | __startTimestamp1493026416733__endTimestamp 02:33:36 INFO - PROCESS | 27444 | 02:33:36 INFO - PROCESS | 27444 | ------- Summary: start ------- 02:33:36 INFO - PROCESS | 27444 | Number of tests: 6 02:33:36 INFO - PROCESS | 27444 | 02:33:36 INFO - PROCESS | 27444 | [#0] dojo.html Cycles:165 Average:5682.82 Median:5902.85 stddev:642.06 (10.9%) stddev-sans-first:617.58 02:33:36 INFO - PROCESS | 27444 | Values: 3357.6 3364.7 3401.1 3412.2 3456.8 5653.3 5709.3 5722.8 5723.9 5742.2 5688.8 5709.2 5735.7 5747.5 5748.3 5193.4 5227.4 5241.9 5247.1 5279.5 5094.5 5292.3 5300.6 5308.0 5331.9 5120.5 5122.1 5156.8 5227.1 5236.5 5194.8 5245.5 5246.2 5246.6 5248.8 5120.4 5160.8 5235.5 5241.2 5266.7 5473.9 5496.5 5608.7 5654.2 5686.8 6112.8 6131.5 6145.4 6151.6 6153.3 6213.3 6213.9 6214.7 6221.0 6225.8 5549.8 5585.9 5702.4 5746.5 5766.5 6275.1 6288.9 6289.5 6289.5 6293.8 5955.9 6036.1 6059.1 6061.3 6075.8 5922.9 5941.3 6008.3 6130.4 6138.8 6098.3 6100.6 6118.6 6133.5 6143.2 5835.7 5869.6 5869.8 6069.5 6089.1 4610.6 4622.4 4646.9 4657.2 4681.5 4083.7 4170.4 4196.0 4211.3 4213.1 5817.9 5849.7 5881.1 5965.5 5978.9 6165.1 6193.9 6198.3 6198.3 6199.2 5925.3 5935.9 5937.3 5943.3 5968.6 5023.0 5038.6 5103.2 5118.2 5201.8 6182.9 6227.7 6239.6 6242.3 6321.8 6153.4 6191.8 6204.5 6205.1 6208.4 6391.3 6405.4 6447.6 6450.3 6451.0 5862.9 5877.9 6075.2 6076.7 6091.6 6038.7 6043.8 6115.7 6126.3 6128.0 5888.5 5961.4 5962.0 5965.0 5995.4 5828.1 5944.6 5955.1 5957.0 5960.4 5888.2 5893.4 5902.9 5989.4 6013.8 5821.9 5857.4 5863.6 5964.7 6006.4 5892.9 5900.8 5938.2 5938.4 5971.0 02:33:36 INFO - PROCESS | 27444 | 02:33:36 INFO - PROCESS | 27444 | [#1] ext.html Cycles:180 Average:44676.36 Median:169841.87 stddev:71093.72 (41.9%) stddev-sans-first:71216.77 02:33:36 INFO - PROCESS | 27444 | Values: 784.5 795.7 796.1 835.8 964.9 39854.2 40679.6 44315.0 44344.8 44438.6 39553.8 39768.4 39805.6 39823.2 39850.6 7150.1 7365.0 7842.1 7952.3 7955.5 10620.7 10790.4 10838.1 10838.8 11202.3 11027.2 11061.9 11067.6 11072.0 11073.1 10847.6 10879.9 10914.8 10926.9 10944.1 10608.8 10639.9 10671.0 10674.6 10699.4 42565.8 42641.4 42838.8 42839.4 42848.8 4192.2 4234.9 4269.9 4356.3 4365.4 29951.3 30217.4 30318.4 30422.5 31617.7 4286.8 4384.9 4390.6 4392.7 4490.9 226564.0 227386.0 228797.0 229729.9 229776.0 38110.2 38742.6 51854.0 55536.4 55776.7 57918.4 57952.4 58156.7 58289.4 58557.0 16869.9 16936.7 16960.6 16967.9 17006.6 10438.2 10610.7 14623.5 15046.1 15099.6 14125.5 14132.4 14180.1 14274.8 14355.8 5615.7 5778.4 5831.6 5835.4 5916.1 1016.4 1024.3 1032.9 1057.5 1069.4 28864.0 29044.4 29508.6 29872.4 29878.1 1074.5 1106.8 1114.1 1120.4 1130.5 14597.6 15528.1 15533.8 15594.8 15744.4 302536.0 303019.0 305708.0 306608.0 308066.0 57371.4 57400.4 57640.4 57688.7 57944.0 1470.9 1508.2 1525.6 1542.0 1545.3 26031.9 27308.6 27619.2 27745.7 27790.0 43316.0 43461.8 43466.6 43534.1 43559.6 278931.0 279265.0 279626.0 280537.0 281362.0 27077.7 27610.0 34806.7 35568.6 36280.1 44884.6 45631.0 46135.5 46315.0 46414.0 33348.0 34045.7 34283.0 34415.5 34674.7 33570.0 33675.8 33851.8 33893.7 34784.1 39444.8 39866.8 39984.4 40004.0 40460.0 32240.4 32798.0 32827.7 32862.8 33203.5 25140.2 26153.3 26442.7 26534.9 26820.6 02:33:36 INFO - PROCESS | 27444 | 02:33:36 INFO - PROCESS | 27444 | [#2] jquery.html Cycles:180 Average:36300.18 Median:12376.52 stddev:102051.34 (824.6%) stddev-sans-first:102302.07 02:33:36 INFO - PROCESS | 27444 | Values: 427.7 434.2 438.3 439.9 443.5 19595.4 19636.1 21451.5 21642.2 21743.6 20460.1 21511.2 22106.7 22122.3 22515.0 6694.2 6885.6 6895.1 6898.9 6901.0 6487.7 6620.2 6673.0 6696.5 6702.1 6453.9 6635.8 6663.4 6717.0 6717.2 6684.1 6703.7 6710.9 6713.9 6714.6 6443.5 6633.8 6692.2 6699.1 6705.7 23945.5 24909.8 25104.0 25302.5 25330.9 3164.7 3279.6 3325.4 3353.9 3359.2 15580.1 16158.8 16459.3 16721.2 17076.3 2182.4 2238.0 2275.8 2276.8 2323.0 112328.0 114892.4 115114.0 115489.0 115528.4 17657.6 17902.0 18114.5 18421.3 18726.3 32181.0 32369.4 32421.5 32499.8 32573.3 10681.5 10886.6 10894.2 10921.0 11166.6 9117.0 9129.5 9160.3 9229.8 9362.8 9149.9 9177.3 9363.4 9508.3 9545.6 3835.6 3949.6 3986.9 4019.1 4038.5 933.7 950.6 962.9 963.8 970.7 20074.6 21542.6 21632.0 21700.3 21848.2 964.2 968.4 978.9 980.5 985.7 11180.2 11886.2 12035.6 12059.6 12083.6 582675.0 583317.0 622769.0 649535.0 650373.0 106414.0 109207.5 109809.0 110288.4 110822.0 1229.6 1237.5 1246.4 1259.1 1275.3 18292.5 18511.2 18691.5 19011.5 19118.5 48822.8 49037.5 49450.8 50089.0 50749.7 63507.4 66407.3 67622.3 67802.7 68867.7 12358.2 12629.4 12682.6 12685.4 12951.5 13487.2 13548.1 13602.0 13635.0 13758.2 12293.8 12387.4 12447.3 12475.3 12499.0 10892.6 10996.7 11020.7 11255.0 11394.0 11787.2 11833.3 12013.2 12029.0 12095.6 12037.3 12087.3 12156.3 12204.2 12491.6 11918.3 12021.3 12255.7 12368.6 12571.4 02:33:36 INFO - PROCESS | 27444 | 02:33:36 INFO - PROCESS | 27444 | [#3] mootools.html Cycles:180 Average:7156.27 Median:2318.20 stddev:4585.07 (197.8%) stddev-sans-first:4575.58 02:33:36 INFO - PROCESS | 27444 | Values: 1132.5 1562.4 1602.9 1607.7 1608.8 8247.8 11168.2 11323.9 11839.6 11867.5 11575.2 11625.4 11625.9 11708.8 11727.9 1935.4 2429.0 2461.5 2467.5 2470.7 2000.5 2032.5 2033.5 2035.4 2349.2 2406.0 2417.5 2419.6 2420.3 2420.6 2290.8 2305.1 2312.2 2313.4 2323.0 2269.5 2290.4 2294.1 2298.0 2302.5 11242.2 11330.6 11336.7 11361.1 11383.4 5786.9 5904.0 5905.2 5942.2 5942.4 3420.9 3470.5 3509.4 3534.6 3577.8 1309.8 1312.6 1333.3 1360.9 1368.8 17097.5 17277.9 17308.6 17312.4 17316.1 4779.8 4914.7 4930.1 4967.9 4973.2 14156.3 14177.7 14178.0 14210.7 14231.6 5756.3 5856.2 5865.9 5877.6 5879.2 5363.2 5471.0 5518.8 5522.0 5526.3 3312.6 3376.2 3947.5 3979.2 4037.6 959.6 984.5 985.2 989.1 989.4 1020.9 1038.5 1048.5 1050.9 1053.9 7442.7 7457.7 7544.4 7560.5 7745.1 1111.5 1114.7 1125.5 1132.8 1142.4 4605.2 4982.7 4989.7 5000.0 5093.5 13715.6 14204.5 14290.0 14312.2 14394.4 13768.9 14046.0 14068.7 14094.8 14219.1 10640.9 10855.2 10855.8 10887.7 10896.5 7219.5 7521.4 7591.7 7614.6 7701.0 12710.9 12869.7 12899.7 12967.9 13164.1 13139.4 13182.1 13229.9 13230.5 13487.6 9803.3 10111.1 10137.8 10139.1 10141.0 8256.9 10766.2 10895.1 10919.3 11103.9 7526.8 8908.6 8929.4 9034.4 9107.7 8823.5 8990.1 9033.9 9133.0 9175.9 8550.8 8585.8 8620.8 8686.2 8745.6 6516.6 6593.0 6609.2 6665.7 6676.4 6667.6 6779.2 6802.9 6812.1 6951.1 02:33:36 INFO - PROCESS | 27444 | 02:33:36 INFO - PROCESS | 27444 | [#4] prototype.html Cycles:180 Average:4814.07 Median:5332.38 stddev:1307.89 (24.5%) stddev-sans-first:1268.61 02:33:36 INFO - PROCESS | 27444 | Values: 385.3 398.8 400.4 414.5 422.4 5254.6 5288.6 5322.0 5342.8 5353.0 5047.1 5154.2 5195.6 5222.8 5225.6 4231.4 4232.2 4243.9 4248.9 4315.8 4239.0 4243.4 4250.5 4254.2 4290.8 4141.0 4174.2 4207.9 4230.6 4253.0 4177.6 4184.5 4187.3 4194.0 4206.8 3827.3 3852.6 3877.8 3884.4 3902.5 4830.8 5156.4 5182.3 5200.7 5208.3 5140.7 5148.3 5158.9 5205.5 5215.9 5577.9 5590.4 5613.8 5646.8 5660.9 4401.8 4635.6 4712.8 4745.5 4746.1 5439.8 5579.6 5802.2 5827.5 5839.5 4475.6 4475.7 4484.0 4489.0 4636.3 4733.9 4736.2 4740.7 4782.7 4795.9 4960.5 5015.8 5080.7 5081.7 5085.8 5501.4 5530.3 5535.3 5549.3 5576.6 1666.4 1682.9 1688.8 1689.6 1690.9 1244.6 1264.9 1285.4 1296.0 1305.2 5313.0 5385.9 5401.9 5478.9 5574.9 5729.6 5755.9 5755.9 5761.9 5765.9 5476.9 5484.5 5491.0 5519.5 5525.9 2911.7 2936.4 3009.2 3010.7 3044.7 5872.9 5879.4 5894.8 5921.5 5925.1 5754.5 5821.8 5860.9 5877.4 5881.8 5664.7 5870.6 5893.8 5898.4 5903.9 5662.5 5673.0 5674.7 5706.4 5752.9 5451.8 5650.6 5694.9 5750.5 5774.2 5620.3 5730.6 5778.8 5938.3 5948.2 4702.6 4736.6 4744.4 4758.7 4763.4 5496.2 5648.9 5702.7 5751.6 5766.3 5561.6 5562.9 5566.6 5566.9 5573.9 5504.6 5513.0 5544.4 5555.6 5558.9 5306.0 5488.8 5536.3 5536.7 5543.3 5585.3 5624.6 5653.7 5665.4 5683.8 5317.8 5554.8 5576.2 5592.9 5594.1 02:33:36 INFO - PROCESS | 27444 | 02:33:36 INFO - PROCESS | 27444 | [#5] yui.html Cycles:180 Average:17752.16 Median:22763.51 stddev:20430.61 (89.8%) stddev-sans-first:20448.40 02:33:36 INFO - PROCESS | 27444 | Values: 830.0 851.6 904.4 910.5 934.7 16948.0 18072.1 18088.7 18118.9 18156.6 12549.8 16707.4 16791.7 16884.8 16888.1 894.4 923.6 926.2 926.9 926.9 915.6 921.5 923.7 925.3 925.6 956.4 960.1 960.3 960.5 960.8 949.1 950.5 950.8 951.1 951.3 698.7 699.0 699.1 699.5 699.9 16739.4 16834.2 16838.6 16862.2 16921.7 17516.5 17579.5 17676.8 17742.6 18161.0 14133.3 14178.7 14209.7 14218.8 14573.5 4097.2 4166.4 4188.1 4265.9 4323.2 54213.0 54948.7 68708.0 70332.3 72809.6 14027.5 14028.0 14073.2 14305.6 14342.1 26370.5 26582.1 26607.5 26630.5 26642.5 6150.1 6236.9 8698.6 9098.4 9204.5 4796.4 5091.9 5119.9 5191.0 5312.4 3755.8 3768.1 3805.9 3825.7 3861.7 599.4 609.3 611.9 620.4 672.8 737.4 758.9 761.8 766.9 768.4 11746.4 11944.6 12074.7 12271.2 12341.9 769.7 777.9 778.3 803.2 806.9 5546.5 5632.2 5705.9 5706.9 5806.8 69682.0 70565.0 82068.0 82143.2 87564.0 59737.0 59742.7 61166.0 62052.4 75663.6 52144.7 53761.5 54489.2 60591.0 62583.4 11947.9 12407.7 12474.9 12514.5 12771.7 37318.8 37743.0 38745.2 39083.0 41360.6 50799.0 51394.7 52283.0 53485.0 56214.4 14232.4 14381.5 14443.8 14499.0 14558.8 15606.9 15632.3 15651.1 15885.1 16287.2 16407.2 16459.3 16609.8 16755.8 16884.3 15754.3 16323.0 16397.2 16863.9 16878.5 14824.3 15091.6 16437.6 16551.0 16554.3 10092.9 10475.1 10489.8 10738.2 10759.5 11383.3 12238.5 12699.5 12912.9 13851.9 02:33:36 INFO - PROCESS | 27444 | -------- Summary: end -------- 02:33:36 INFO - PROCESS | 27444 | 02:33:37 INFO - TEST-INFO | 27444: exit 0 02:33:37 INFO - TEST-OK | dromaeo_css | took 1116379ms 02:33:37 INFO - TEST-START | kraken 02:33:37 INFO - operating with platform_type : linux_ 02:33:37 INFO - Initialising browser for kraken test... 02:33:37 INFO - TEST-INFO | started process 27774 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp5PlHq/profile http://localhost:38796/getInfo.html) 02:33:41 INFO - PROCESS | 27774 | __metrics Screen width/height:1600/1200 02:33:41 INFO - PROCESS | 27774 | colorDepth:24 02:33:41 INFO - PROCESS | 27774 | Browser inner width/height: 1024/697 02:33:41 INFO - PROCESS | 27774 | __metrics 02:33:42 INFO - PROCESS | 27774 | 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" 02:33:42 INFO - PROCESS | 27774 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:33:43 INFO - TEST-INFO | 27774: exit 0 02:33:43 INFO - Browser initialized. 02:33:43 INFO - Running cycle 1/1 for kraken test... 02:33:43 INFO - TEST-INFO | started process 27867 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpp5PlHq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:33:55 INFO - PROCESS | 27867 | RSS: Main: 140570624 02:33:55 INFO - PROCESS | 27867 | 02:33:58 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38796/tests/kraken/driver.html?testName=audio-beat-detection) 02:33:58 INFO - PROCESS | 27867 | RSS: Main: 183455744 02:33:58 INFO - PROCESS | 27867 | 02:34:01 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38796/tests/kraken/driver.html?testName=audio-dft) 02:34:02 INFO - PROCESS | 27867 | RSS: Main: 174456832 02:34:02 INFO - PROCESS | 27867 | 02:34:04 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38796/tests/kraken/driver.html?testName=audio-fft) 02:34:05 INFO - PROCESS | 27867 | RSS: Main: 266076160 02:34:05 INFO - PROCESS | 27867 | 02:34:07 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38796/tests/kraken/driver.html?testName=audio-oscillator) 02:34:08 INFO - PROCESS | 27867 | RSS: Main: 172265472 02:34:08 INFO - PROCESS | 27867 | 02:34:10 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38796/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:34:10 INFO - PROCESS | 27867 | RSS: Main: 174948352 02:34:10 INFO - PROCESS | 27867 | 02:34:13 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38796/tests/kraken/driver.html?testName=imaging-darkroom) 02:34:14 INFO - PROCESS | 27867 | RSS: Main: 214302720 02:34:14 INFO - PROCESS | 27867 | 02:34:17 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38796/tests/kraken/driver.html?testName=imaging-desaturate) 02:34:18 INFO - PROCESS | 27867 | RSS: Main: 212684800 02:34:18 INFO - PROCESS | 27867 | 02:34:21 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38796/tests/kraken/driver.html?testName=json-parse-financial) 02:34:21 INFO - PROCESS | 27867 | RSS: Main: 211472384 02:34:21 INFO - PROCESS | 27867 | 02:34:23 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38796/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:34:23 INFO - PROCESS | 27867 | RSS: Main: 173613056 02:34:23 INFO - PROCESS | 27867 | 02:34:25 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38796/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:34:26 INFO - PROCESS | 27867 | RSS: Main: 180768768 02:34:26 INFO - PROCESS | 27867 | 02:34:28 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38796/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:34:28 INFO - PROCESS | 27867 | RSS: Main: 189947904 02:34:28 INFO - PROCESS | 27867 | 02:34:31 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38796/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:34:31 INFO - PROCESS | 27867 | RSS: Main: 199237632 02:34:31 INFO - PROCESS | 27867 | 02:34:34 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38796/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:34:35 INFO - PROCESS | 27867 | RSS: Main: 194625536 02:34:35 INFO - PROCESS | 27867 | 02:34:36 INFO - PROCESS | 27867 | Cycle 1(1): loaded http://localhost:38796/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38796/tests/kraken/driver.html?testName=ai-astar) 02:34:37 INFO - PROCESS | 27867 | RSS: Main: 245493760 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | __start_tp_report 02:34:37 INFO - PROCESS | 27867 | _x_x_mozilla_page_load 02:34:37 INFO - PROCESS | 27867 | _x_x_mozilla_page_load_details 02:34:37 INFO - PROCESS | 27867 | |i|pagename|runs| 02:34:37 INFO - PROCESS | 27867 | |0;ai-astar;103;112;112;113;112;113;113;113;113;113 02:34:37 INFO - PROCESS | 27867 | |1;audio-beat-detection;145;123;115;151;115;115;114;114;139;116 02:34:37 INFO - PROCESS | 27867 | |2;audio-dft;162;162;161;160;161;162;160;159;162;160 02:34:37 INFO - PROCESS | 27867 | |3;audio-fft;74;75;75;122;74;76;76;75;102;77 02:34:37 INFO - PROCESS | 27867 | |4;audio-oscillator;82;83;102;83;83;82;91;83;83;95 02:34:37 INFO - PROCESS | 27867 | |5;imaging-gaussian-blur;113;114;112;112;113;112;113;112;113;112 02:34:37 INFO - PROCESS | 27867 | |6;imaging-darkroom;147;147;147;147;147;147;146;147;147;147 02:34:37 INFO - PROCESS | 27867 | |7;imaging-desaturate;94;93;94;94;93;94;94;94;94;94 02:34:37 INFO - PROCESS | 27867 | |8;json-parse-financial;56;54;53;54;53;55;54;54;53;55 02:34:37 INFO - PROCESS | 27867 | |9;json-stringify-tinderbox;54;54;53;53;53;54;53;53;54;53 02:34:37 INFO - PROCESS | 27867 | |10;stanford-crypto-aes;79;79;85;76;76;86;80;83;82;75 02:34:37 INFO - PROCESS | 27867 | |11;stanford-crypto-ccm;121;120;120;127;126;120;119;130;156;129 02:34:37 INFO - PROCESS | 27867 | |12;stanford-crypto-pbkdf2;161;161;159;159;160;162;160;157;161;157 02:34:37 INFO - PROCESS | 27867 | |13;stanford-crypto-sha256-iterative;61;59;62;59;61;59;60;63;59;58 02:34:37 INFO - PROCESS | 27867 | __end_tp_report 02:34:37 INFO - PROCESS | 27867 | __start_cc_report 02:34:37 INFO - PROCESS | 27867 | _x_x_mozilla_cycle_collect,1688 02:34:37 INFO - PROCESS | 27867 | __end_cc_report 02:34:37 INFO - PROCESS | 27867 | __startTimestamp1493026477119__endTimestamp 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | ------- Summary: start ------- 02:34:37 INFO - PROCESS | 27867 | Number of tests: 14 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.09 (2.7%) stddev-sans-first:0.50 02:34:37 INFO - PROCESS | 27867 | Values: 103.0 112.0 112.0 113.0 112.0 113.0 113.0 113.0 113.0 113.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:119.50 stddev:14.52 (12.2%) stddev-sans-first:13.42 02:34:37 INFO - PROCESS | 27867 | Values: 145.0 123.0 115.0 151.0 115.0 115.0 114.0 114.0 139.0 116.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.50 stddev:1.10 (0.7%) stddev-sans-first:1.09 02:34:37 INFO - PROCESS | 27867 | Values: 162.0 162.0 161.0 160.0 161.0 162.0 160.0 159.0 162.0 160.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:16.22 (21.6%) stddev-sans-first:16.90 02:34:37 INFO - PROCESS | 27867 | Values: 74.0 75.0 75.0 122.0 74.0 76.0 76.0 75.0 102.0 77.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:83.00 stddev:6.95 (8.4%) stddev-sans-first:7.16 02:34:37 INFO - PROCESS | 27867 | Values: 82.0 83.0 102.0 83.0 83.0 82.0 91.0 83.0 83.0 95.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#5] imaging-gaussian-blur Cycles:10 Average:112.60 Median:113.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 02:34:37 INFO - PROCESS | 27867 | Values: 113.0 114.0 112.0 112.0 113.0 112.0 113.0 112.0 113.0 112.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 02:34:37 INFO - PROCESS | 27867 | Values: 147.0 147.0 147.0 147.0 147.0 147.0 146.0 147.0 147.0 147.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:34:37 INFO - PROCESS | 27867 | Values: 94.0 93.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.99 (1.8%) stddev-sans-first:0.78 02:34:37 INFO - PROCESS | 27867 | Values: 56.0 54.0 53.0 54.0 53.0 55.0 54.0 54.0 53.0 55.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#9] json-stringify-tinderbox Cycles:10 Average:53.40 Median:53.50 stddev:0.52 (1.0%) stddev-sans-first:0.50 02:34:37 INFO - PROCESS | 27867 | Values: 54.0 54.0 53.0 53.0 53.0 54.0 53.0 53.0 54.0 53.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:81.00 stddev:3.84 (4.7%) stddev-sans-first:4.06 02:34:37 INFO - PROCESS | 27867 | Values: 79.0 79.0 85.0 76.0 76.0 86.0 80.0 83.0 82.0 75.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:126.50 stddev:11.06 (8.7%) stddev-sans-first:11.53 02:34:37 INFO - PROCESS | 27867 | Values: 121.0 120.0 120.0 127.0 126.0 120.0 119.0 130.0 156.0 129.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.70 Median:160.50 stddev:1.70 (1.1%) stddev-sans-first:1.74 02:34:37 INFO - PROCESS | 27867 | Values: 161.0 161.0 159.0 159.0 160.0 162.0 160.0 157.0 161.0 157.0 02:34:37 INFO - PROCESS | 27867 | 02:34:37 INFO - PROCESS | 27867 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.10 Median:60.50 stddev:1.60 (2.6%) stddev-sans-first:1.66 02:34:37 INFO - PROCESS | 27867 | Values: 61.0 59.0 62.0 59.0 61.0 59.0 60.0 63.0 59.0 58.0 02:34:37 INFO - PROCESS | 27867 | -------- Summary: end -------- 02:34:37 INFO - PROCESS | 27867 | 02:34:38 INFO - TEST-INFO | 27867: exit 0 02:34:38 INFO - TEST-OK | kraken | took 60621ms 02:34:38 INFO - SUITE-END | took 1177s 02:34:38 INFO - Completed test suite (00:19:37) 02:34:38 INFO - javascript benchmark 02:34:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7833.5523690965165, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3357.647447018063, 3364.6635336466356, 3401.0647072055167, 3412.1981334386433, 3456.7925924444535, 5653.349864765553, 5709.257796486456, 5722.771089156434, 5723.942760572393, 5742.167385729077, 5688.800891968782, 5709.172170035343, 5735.713214339287, 5747.511461525775, 5748.338941021784, 5193.417076642615, 5227.372715274172, 5241.8951620967555, 5247.055530004598, 5279.472052794728, 5094.46508116648, 5292.311999440068, 5300.602454815885, 5307.973460132694, 5331.866703332428, 5120.513551212647, 5122.129238029525, 5156.767945442464, 5227.137522308814, 5236.528712415882, 5194.818181363653, 5245.527902488794, 5246.213068039787, 5246.606504512177, 5248.8425347239645, 5120.359955005624, 5160.767765450564, 5235.502627250406, 5241.213817927303, 5266.683998960037, 5473.945260547383, 5496.4503549645115, 5608.719564021823, 5654.180143879114, 5686.829395118111, 6112.76377709902, 6131.480086409457, 6145.432914606791, 6151.6001459904965, 6153.261608606951, 6213.285472170713, 6213.906791398133, 6214.720337584819, 6221.035739460391, 6225.844353891143, 5549.805756798533, 5585.888282234373, 5702.429757024347, 5746.540276777848, 5766.538676905838, 6275.121482992385, 6288.874222515616, 6289.465395441347, 6289.528285378615, 6293.811185664437, 5955.880882382328, 6036.094585812163, 6059.091136329498, 6061.302950160788, 6075.75696972125, 5922.8815423691285, 5941.287045554474, 6008.309044459943, 6130.448259656563, 6138.815835524942, 6098.268207815002, 6100.633961962297, 6118.60229085108, 6133.539984501181, 6143.170671959318, 5835.708214589338, 5869.618474799125, 5869.765209391577, 6069.484093852072, 6089.117078023662, 4610.561996610384, 4622.375979242826, 4646.860594182112, 4657.184992626277, 4681.50844161365, 4083.734557253769, 4170.416141740159, 4195.958040419618, 4211.263028969918, 4213.136307057046, 5817.88364232713, 5849.736761845642, 5881.117832325185, 5965.492933100735, 5978.910316345258, 6165.075238714139, 6193.907091393633, 6198.318184999646, 6198.349173336825, 6199.194104766441, 5925.34821169671, 5935.910961335583, 5937.346891841894, 5943.257092863392, 5968.582199246098, 5023.0, 5038.571721403649, 5103.157978933508, 5118.155504341817, 5201.765920533585, 6182.876342473125, 6227.657478838707, 6239.594426362272, 6242.2509298883515, 6321.841953951188, 6153.353897840662, 6191.7523299069335, 6204.472619827366, 6205.069239614094, 6208.410201030895, 6391.265004524539, 6405.423511883766, 6447.6453795041725, 6450.290468048604, 6451.032345148265, 5862.94137058641, 5877.911831322619, 6075.1798507200965, 6076.696165191811, 6091.573589848668, 6038.698065096815, 6043.848903777441, 6115.727150604437, 6126.27339965311, 6128.014278093809, 5888.499477544457, 5961.4038596140035, 5961.986462301333, 5965.015772397506, 5995.430434108753, 5828.067509198508, 5944.6433214005565, 5955.076963070731, 5957.046872500379, 5960.403959604005, 5888.234529511136, 5893.410658934244, 5902.852428689317, 5989.431004054608, 6013.849653758691, 5821.854453638693, 5857.384974577608, 5863.648181109147, 5964.731587078679, 6006.3693312201585, 5892.94107058924, 5900.822975310747, 5938.198343223609, 5938.435848594377, 5971.044632858721], "value": 5640.304953963124, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.4508843809335, 795.6578671171394, 796.1083586383252, 835.7785186925464, 964.8890377606577, 39854.20291594166, 40679.59320406795, 44315.0, 44344.77827610858, 44438.555614443845, 39553.802230988804, 39768.40347394792, 39805.601943980626, 39823.203535929264, 39850.60149398505, 7150.10623672041, 7364.963175184116, 7842.058952925643, 7952.324052455549, 7955.522668639871, 10620.681379558588, 10790.406527640976, 10838.132949364031, 10838.837417438705, 11202.327860328365, 11027.17296202784, 11061.944690276578, 11067.557297708123, 11072.0, 11073.114150867912, 10847.620333288376, 10879.891201087967, 10914.781704365947, 10926.890731092706, 10944.069754070913, 10608.787824243473, 10639.946800266027, 10671.039606435457, 10674.573017079307, 10699.411532365717, 42565.787171063945, 42641.36037959402, 42838.78580607108, 42839.357409638884, 42848.78575607133, 4192.24539582875, 4234.894127646803, 4269.914601707979, 4356.281213599752, 4365.43249377582, 29951.251218719488, 30217.395652087056, 30318.393632127456, 30422.54366184509, 31617.73529058835, 4286.807093680792, 4384.934225986613, 4390.604845563883, 4392.714473559208, 4490.865274041784, 226564.0, 227386.0, 228797.0, 229729.85135074385, 229776.0, 38110.23779524394, 38742.61257387447, 51854.0, 55536.44463555393, 55776.72111639497, 57918.420815792146, 57952.420475795545, 58156.709216453644, 58289.41710582925, 58557.0, 16869.91565042167, 16936.661266774594, 16960.57598560021, 16967.91516042412, 17006.57483562921, 10438.164946804238, 10610.681679549625, 14623.488177913721, 15046.097234165985, 15099.622509437351, 14125.505607303696, 14132.364043618061, 14180.078294910798, 14274.785878211835, 14355.78466323006, 5615.719214039282, 5778.3643799182055, 5831.620944638585, 5835.416458354131, 5916.1421593868645, 1016.3929520779178, 1024.2676486312323, 1032.9431881246603, 1057.5188289328337, 1069.3797597393493, 28864.0, 29044.419111618074, 29508.557371639443, 29872.40255194884, 29878.10365689033, 1074.5110974506738, 1106.7841770854768, 1114.0753174864842, 1120.4453795371344, 1130.463030060731, 14597.562073137822, 15528.14595197275, 15533.766993495106, 15594.766078508832, 15744.370225190863, 302536.0, 303019.0, 305708.0, 306608.0, 308066.0, 57371.426285736605, 57400.42599574034, 57640.42359576435, 57688.71155644279, 57944.0, 1470.867621914033, 1508.1931166825914, 1525.6491007068214, 1541.9845801542065, 1545.2505534815605, 26031.869840650674, 27308.634568271904, 27619.171424857286, 27745.722542775122, 27790.0, 43316.0, 43461.782691086344, 43466.565334346255, 43534.12931741411, 43559.56440435555, 278931.0, 279265.0, 279626.0, 280537.0, 281362.0, 27077.72922270752, 27610.0, 34806.65193348136, 35568.644313556535, 36280.09299767527, 44884.551154488036, 45631.0, 46135.53864461447, 46315.0, 46414.0, 33348.0, 34045.65954340425, 34283.0, 34415.483767743004, 34674.653253468154, 33569.992900212055, 33675.83162084174, 33851.83074084614, 33893.661063390035, 34784.130396740286, 39444.80277598594, 39866.80066599648, 39984.4002339971, 40004.0, 40460.0, 32240.387980601346, 32798.01605951825, 32827.67172328246, 32862.83568582142, 33203.50194747129, 25140.24579262625, 26153.346166345993, 26442.735572644797, 26534.867325663246, 26820.59769103426], "value": 19051.742099978685, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.6514640567939, 434.15556742136545, 438.314038529701, 439.92301347264225, 443.4523363645899, 19595.412137635874, 19636.116374763133, 21451.463713407134, 21642.24252151175, 21743.565128697417, 20460.07929643166, 21511.247106351282, 22106.668399974013, 22122.33632991009, 22515.0, 6694.230163531195, 6885.586864788129, 6895.069165662647, 6898.896516552256, 6901.0, 6487.708053137597, 6620.172478440195, 6673.032410300503, 6696.531242813005, 6702.061711360413, 6453.903191452108, 6635.8009259722285, 6663.400293973541, 6716.992451132321, 6717.16035495563, 6684.131062961808, 6703.698333575001, 6710.865782684319, 6713.865722685568, 6714.6306953117555, 6443.516736244754, 6633.800985970429, 6692.23039350476, 6699.095622091004, 6705.664716764142, 23945.521089578284, 24909.750902491105, 25104.0, 25302.493950120894, 25330.87334563334, 3164.699353561408, 3279.5572597699243, 3325.3681800458075, 3353.8658453661824, 3359.160209947513, 15580.143092129929, 16158.838411615969, 16459.341626335052, 16721.247543860554, 17076.31694732209, 2182.4216582605623, 2238.0488292475634, 2275.8293128015466, 2276.8064714499287, 2323.035940084863, 112328.0, 114892.42553787178, 115114.0, 115489.0, 115528.42235788851, 17657.646847063243, 17902.0, 18114.456566302768, 18421.26314947387, 18726.344577939708, 32181.0, 32369.35261294761, 32421.51367729486, 32499.837500812344, 32573.348533029206, 10681.519331630096, 10886.564537418415, 10894.237403381687, 10921.0, 11166.553337866557, 9117.0, 9129.452232865917, 9160.267178625694, 9229.815403692022, 9362.765930851783, 9149.862752058725, 9177.338571814233, 9363.391379560308, 9508.286878484141, 9545.570449329796, 3835.6355796870075, 3949.5852935441367, 3986.920261594752, 4019.070845993881, 4038.4749982502085, 933.7245512573807, 950.5674917912334, 962.8748262725801, 963.8361478548663, 970.6893793985998, 20074.598508029758, 21542.569148617255, 21632.0, 21700.34898953034, 21848.235311764012, 964.2286171063122, 968.3608818179968, 978.8678528398717, 980.4852452462515, 985.7239972807625, 11180.161487888277, 11886.227395219283, 12035.638930832089, 12059.638210853689, 12083.637490875288, 582675.0, 583317.0, 622769.0, 649535.0, 650373.0, 106414.0, 109207.45396272968, 109809.0, 110288.4485577583, 110821.99999999838, 1229.557359350633, 1237.4926280225066, 1246.3643541793751, 1259.0808709641829, 1275.2922128218927, 18292.45122646323, 18511.166997484877, 18691.532711682317, 19011.486459537908, 19118.522036949187, 48822.755886220344, 49037.509624903294, 49450.75274623604, 50089.0, 50749.74625126851, 63507.36492635014, 66407.33592664011, 67622.32377676161, 67802.66098669475, 68867.65566172136, 12358.196717213352, 12629.368531573567, 12682.619521414372, 12685.4291556882, 12951.481940722642, 13487.190768553919, 13548.119372240773, 13602.047856649939, 13634.977376696588, 13758.174509529461, 12293.754124917272, 12387.380630968595, 12447.253164810141, 12475.313857737912, 12499.0, 10892.564297428331, 10996.670099897015, 11020.669379918616, 11254.987051165434, 11394.0, 11787.174897757075, 11833.290002599872, 12013.219140755822, 12029.0, 12095.57665481704, 12037.337946413032, 12087.274763513866, 12156.270623762603, 12204.206726562745, 12491.625251242114, 11918.344491053076, 12021.338826364634, 12255.653649749098, 12368.628941131781, 12571.434285457359], "value": 11385.952220609222, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1132.5300000499792, 1562.39066763962, 1602.8557429831317, 1607.6865011322793, 1608.7828143200672, 8247.793805154874, 11168.162387820916, 11323.886761132377, 11839.644810655673, 11867.525298988052, 11575.189736718434, 11625.418729063555, 11625.883741162586, 11708.824367634472, 11727.941360293207, 1935.4000259919399, 2429.0283886445477, 2461.5323088613195, 2467.481828815951, 2470.654108424813, 2000.5398758285603, 2032.4918770307422, 2033.4916270932267, 2035.4300795777206, 2349.1777877742825, 2406.0, 2417.5164967006667, 2419.6249581314923, 2420.2860156253946, 2420.5521978434044, 2290.7709229077127, 2305.147095574648, 2312.213847291912, 2313.410080429488, 2322.988385058081, 2269.5460907818338, 2290.358699564125, 2294.1167650454527, 2298.0348253733473, 2302.481941563152, 11242.213045086848, 11330.603428880033, 11336.659900203007, 11361.14791390649, 11383.430828458626, 5786.942130578683, 5903.9668058089665, 5905.202797622309, 5942.227510423617, 5942.37605051472, 3420.863165473378, 3470.4794280858073, 3509.438489841639, 3534.5935217450065, 3577.821108944542, 1309.8428188617331, 1312.59309614019, 1333.320006796531, 1360.9115407498482, 1368.835739711231, 17097.48707538776, 17277.91361043199, 17308.567285817957, 17312.394066207748, 17316.134193290283, 4779.808807647724, 4914.729689867092, 4930.137225985438, 4967.850964471072, 4973.204287314013, 14156.29218539069, 14177.716445671234, 14178.0, 14210.715785684228, 14231.64420889486, 5756.30924289088, 5856.1508581255475, 5865.941340586625, 5877.5591830612875, 5879.176915231871, 5363.168708850135, 5471.015217260851, 5518.834434966958, 5522.0, 5526.336839579196, 3312.6190488093694, 3376.1728376547903, 3947.50656167979, 3979.2240513100246, 4037.6366127048705, 959.5825815770162, 984.5077461269366, 985.1675334342469, 989.0554520432969, 989.3766926836047, 1020.9132223760915, 1038.5170895533613, 1048.533402635835, 1050.8686414198226, 1053.89461053894, 7442.66508007141, 7457.7016919323705, 7544.396448284124, 7560.508566943131, 7745.148033716287, 1111.4998250787016, 1114.6656003199007, 1125.5441546173813, 1132.7916789128428, 1142.4173671427466, 4605.240135377692, 4982.725950072709, 4989.700617962933, 4999.950000499948, 5093.516115968977, 13715.588532343847, 14204.502842400467, 14290.0, 14312.212828294336, 14394.424223030961, 13768.93115534436, 14046.0, 14068.71862562743, 14094.788578171336, 14219.075760075562, 10640.946795265974, 10855.2401331906, 10855.782884342269, 10887.727806804893, 10896.509657065451, 7219.530730502501, 7521.398288137046, 7591.696332146873, 7614.619269036637, 7701.0, 12710.872891270968, 12869.678258043623, 12899.677508062374, 12967.870321297043, 13164.078514503894, 13139.408726607135, 13182.077254592086, 13229.867701323248, 13230.470781168646, 13487.595372138852, 9803.264755143535, 10111.140553053074, 10137.797244055226, 10139.138173255062, 10141.036601522843, 8256.876146857681, 10766.246362754846, 10895.128389728961, 10919.344839309666, 11103.944480277547, 7526.811829704301, 8908.643654253758, 8929.374943753875, 9034.412763170372, 9107.726768196964, 8823.514706691194, 8990.100989900957, 9033.864492032757, 9133.041030691948, 9175.862362064709, 8550.786230344042, 8585.785355366166, 8620.827583448421, 8686.174813392718, 8745.60644770974, 6516.609003459807, 6592.967035164794, 6609.20689517261, 6665.700043498151, 6676.365745254193, 6667.633280169443, 6779.15260592426, 6802.8639427212165, 6812.14848143982, 6951.131108611424], "value": 5382.109718679556, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.2910644414278, 398.7906349166688, 400.3754143536084, 414.5398607545622, 422.4487044407048, 5254.553362964148, 5288.629795914288, 5322.0, 5342.759575819087, 5353.0, 5047.132560952453, 5154.226865970107, 5195.58435325174, 5222.843314700555, 5225.555827754636, 4231.428757117796, 4232.238197124517, 4243.893902652443, 4248.872533823975, 4315.82736690532, 4238.97880510597, 4243.405923170759, 4250.48994120706, 4254.170436764816, 4290.849820256291, 4141.0, 4174.24863524567, 4207.873763787091, 4230.619244268015, 4252.957470425303, 4177.621384942992, 4184.456020717318, 4187.309093999486, 4193.97903010483, 4206.768627725474, 3827.2919509890758, 3852.5569559500736, 3877.8448862045484, 3884.3979183226643, 3902.4926759521363, 4830.806767729287, 5156.381234251876, 5182.32629758129, 5200.661956972823, 5208.322918020633, 5140.7172605506685, 5148.304978827848, 5158.9484105158845, 5205.505476979719, 5215.921761173586, 5577.9401913636275, 5590.385057643656, 5613.831585052455, 5646.769526037233, 5660.886782264372, 4401.845935392246, 4635.582797548237, 4712.835050773241, 4745.525447455261, 4746.09824133417, 5439.809606663787, 5579.553635709133, 5802.158686990363, 5827.504662103726, 5839.503642190419, 4475.619572336323, 4475.731456112644, 4484.0, 4489.037235424604, 4636.327732478771, 4733.863872670534, 4736.171170045228, 4740.739259340769, 4782.665213435096, 4795.86583970137, 4960.50394960501, 5015.774290156952, 5080.695158290514, 5081.695098294114, 5085.847424577269, 5501.432091853802, 5530.253415788898, 5535.33575970878, 5549.250851135126, 5576.6375185613615, 1666.4334126397084, 1682.932682692703, 1688.8178275207404, 1689.5501522792001, 1690.8900921440068, 1244.6056354078426, 1264.943077561512, 1285.4279845468677, 1296.0215037646706, 1305.2494815481136, 5312.963972025495, 5385.892282154335, 5401.891962160814, 5478.890422191614, 5574.86062848432, 5729.5702822288495, 5755.90637778829, 5755.927865968248, 5761.942380576224, 5765.942340576625, 5476.890462190813, 5484.451554844483, 5490.972545137249, 5519.475649813261, 5525.861853453695, 2911.650601927782, 2936.3980384021224, 3009.172477568686, 3010.6838781928022, 3044.695530446982, 5872.941270587325, 5879.353271140171, 5894.823155305347, 5921.467067963904, 5925.074350835996, 5754.539636829044, 5821.767129314865, 5860.882782344329, 5877.412258774174, 5881.764729410861, 5664.688442135722, 5870.6204042050085, 5893.789828814195, 5898.423258067224, 5903.852403689943, 5662.5469962402085, 5673.042800233913, 5674.659520428788, 5706.372299047019, 5752.942470575241, 5451.810690745958, 5650.63270887391, 5694.92135370595, 5750.539956803529, 5774.220480235283, 5620.297462817148, 5730.627509211888, 5778.76389222037, 5938.346781853907, 5948.196993405835, 4702.623790096715, 4736.621070314298, 4744.430668319824, 4758.666893317434, 4763.3807605011125, 5496.230527726041, 5648.858778530405, 5702.6863522505, 5751.577811202328, 5766.250387449605, 5561.582881284001, 5562.860928476821, 5566.554675626021, 5566.860828479321, 5573.860653483695, 5504.6421982571, 5513.0, 5544.445555444423, 5555.638868477249, 5558.888822223452, 5305.973470132624, 5488.792465657549, 5536.252605898313, 5536.667799932017, 5543.307086614173, 5585.301837270341, 5624.634398764068, 5653.745581448762, 5665.376808551138, 5683.811732482941, 5317.813876514303, 5554.777808887599, 5576.163575463713, 5592.916106258491, 5594.0769772987005], "value": 4433.041272980218, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.991700082999, 851.6380538271234, 904.4347282948157, 910.5492781073367, 934.7149119518547, 16948.0, 18072.096395180222, 18088.72866906994, 18118.90940545297, 18156.636867262663, 12549.811752823714, 16707.415240466587, 16791.748123778154, 16884.83115168848, 16888.07115608644, 894.3605322194629, 923.5797712041006, 926.1988380051274, 926.8517037274038, 926.9119433653792, 915.5513798238849, 921.5346250143701, 923.6905636611743, 925.2690374604055, 925.6066171876959, 956.3879117364892, 960.0543464687278, 960.2701946520659, 960.4909398019026, 960.7838236396825, 949.1315446366566, 950.5009869818366, 950.7908260182749, 951.1392190068, 951.2770294576103, 698.671624336561, 699.0074094785417, 699.0911814640947, 699.5313140196081, 699.8600279944031, 16739.414120505724, 16834.158292085347, 16838.579035524086, 16862.24119914607, 16921.661566768595, 17516.474505764847, 17579.472615821545, 17676.823231767645, 17742.55643608907, 18161.0, 14133.293335333192, 14178.71642567143, 14209.740065730526, 14218.786718199235, 14573.489927852474, 4097.242010228118, 4166.437530933315, 4188.141431006637, 4265.850695225652, 4323.1785960667685, 54213.0, 54948.725256373466, 68708.0, 70332.29667703308, 72809.6359518199, 14027.509037183854, 14028.0, 14073.225972571607, 14305.64235894111, 14342.067765595202, 26370.47259054808, 26582.06962756333, 26607.46785064288, 26630.467390652466, 26642.467150657267, 6150.07748837669, 6236.875262494816, 8698.565071746387, 9098.363114582047, 9204.493752843659, 4796.424429068534, 5091.9011490133, 5119.8720031998755, 5190.974045129826, 5312.3890752563175, 3755.7746535207975, 3768.0768211788286, 3805.904852378713, 3825.6748176405317, 3861.7296789225047, 599.4365296621162, 609.2810483629271, 611.9204503414617, 620.3610281410155, 672.7510820996263, 737.4321772235456, 758.8823732321499, 761.8285885675789, 766.8657984852629, 768.3814529303852, 11746.412679365998, 11944.573347504374, 12074.698132546757, 12271.202371845802, 12341.876581234252, 769.6651956398872, 777.8677624803797, 778.2645400096854, 803.2168635580263, 806.8507326144683, 5546.528545073633, 5632.183333416713, 5705.885882282331, 5706.896207385523, 5806.767729290781, 69682.0, 70565.0, 82068.0, 82143.17856821355, 87564.0, 59737.0, 59742.70128649416, 61166.0, 62052.37947620466, 75663.62168189124, 52144.73927630338, 53761.46238537643, 54489.1826622601, 60591.0, 62583.37416625775, 11947.940260298643, 12407.689807754878, 12474.875251247371, 12514.499420023098, 12771.680707982376, 37318.81340593279, 37743.0, 38745.2250954985, 39083.02292442712, 41360.58639413688, 50799.0, 51394.74302628463, 52283.0, 53485.0, 56214.43785562092, 14232.430702771773, 14381.496647617283, 14443.84449244079, 14499.0, 14558.781618275947, 15606.9219653901, 15632.2965466552, 15651.139187344805, 15885.126318052618, 16287.185640718153, 16407.17964101766, 16459.341626335292, 16609.833901661314, 16755.832441675913, 16884.324627014783, 15754.291056902695, 16323.0, 16397.18014099314, 16863.91568042152, 16878.493645190665, 14824.258787060387, 15091.622709432351, 16437.58906027359, 16551.00693958366, 16554.255058522154, 10092.899071009197, 10475.109615682752, 10489.842652360374, 10738.248322617654, 10759.515821788194, 11383.317000979967, 12238.510459581517, 12699.492020319083, 12912.870871291167, 13851.930740346637], "value": 7915.033072123385, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 112.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 123.0, 115.0, 151.0, 115.0, 115.0, 114.0, 114.0, 139.0, 116.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 160.0, 161.0, 162.0, 160.0, 159.0, 162.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 122.0, 74.0, 76.0, 76.0, 75.0, 102.0, 77.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 102.0, 83.0, 83.0, 82.0, 91.0, 83.0, 83.0, 95.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 112.0, 112.0, 113.0, 112.0, 113.0, 112.0, 113.0, 112.0], "value": 112.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 53.0, 54.0, 53.0, 55.0, 54.0, 54.0, 53.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 53.0, 53.0, 54.0, 53.0, 53.0, 54.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 85.0, 76.0, 76.0, 86.0, 80.0, 83.0, 82.0, 75.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 120.0, 127.0, 126.0, 120.0, 119.0, 130.0, 156.0, 129.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 159.0, 159.0, 160.0, 162.0, 160.0, 157.0, 161.0, 157.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 62.0, 59.0, 61.0, 59.0, 60.0, 63.0, 59.0, 58.0], "value": 60.1, "unit": "score"}]}]} 02:34:38 INFO - Return code: 0 02:34:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:34:38 ERROR - # TBPL SUCCESS # 02:34:38 INFO - Running post-action listener: _resource_record_post_action 02:34:38 INFO - [mozharness: 2017-04-24 09:34:38.417192Z] Finished run-tests step (success) 02:34:38 INFO - Running post-run listener: _resource_record_post_run 02:34:38 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 266240; Write bytes: 363470848; Read time: 28; Write time: 322812 02:34:38 INFO - TinderboxPrint: CPU usage
13.2% 02:34:38 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 02:34:38 INFO - TinderboxPrint: I/O write bytes / time
363,470,848 / 322,812 02:34:38 INFO - TinderboxPrint: CPU idle
8,273.9 (86.8%) 02:34:38 INFO - TinderboxPrint: CPU user
1,205.4 (12.6%) 02:34:38 INFO - TinderboxPrint: Swap in / out
0 / 0 02:34:38 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111058944; Read time: 0; Write time: 122972 02:34:38 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 266240; Write bytes: 252411904; Read time: 28; Write time: 199840 02:34:39 INFO - Running post-run listener: _upload_blobber_files 02:34:39 INFO - Blob upload gear active. 02:34:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:34:39 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 02:34:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:34:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:34:39 INFO - (blobuploader) - INFO - Open directory for files ... 02:34:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:34:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:34:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:40 INFO - (blobuploader) - INFO - Done attempting. 02:34:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:34:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:34:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:41 INFO - (blobuploader) - INFO - Done attempting. 02:34:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:34:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:34:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:42 INFO - (blobuploader) - INFO - Done attempting. 02:34:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:34:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:34:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:45 INFO - (blobuploader) - INFO - Done attempting. 02:34:45 INFO - (blobuploader) - INFO - Iteration through files over. 02:34:45 INFO - Return code: 0 02:34:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:34:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:34:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7c3d590c4590e31f23ec88a51504b1816069f1f2b0724d2d2fac717dd259d18134098e56d008db9caff80379f43c7d1ca96042bda287af147f5a6aa07dbfc794", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dbfa0dd868b40d631dd23479d12a456b0ff1523c6fea8bbfc3028714d09f062e2aff7ca73adb7b1a2db949fad7981a4422d57da6a87c8de24064525ec1effc72", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1331ea68c6573f96a21c0649caa937cfc5db70fa8683f7a5656e88de7dcebb41fcf4a8842be4f773448b3858ead9d41fffea182b6118b06025c7b623f4861f5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5cc1d93e42f7c9b25814134e4b6106a743b3e2deb5a770704d27be4495deb2b37ef01bbec929c091d0fb555db2459c2eca0094a167d48b4b978b28dd003d48ce"} 02:34:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:34:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:34:45 INFO - Contents: 02:34:45 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7c3d590c4590e31f23ec88a51504b1816069f1f2b0724d2d2fac717dd259d18134098e56d008db9caff80379f43c7d1ca96042bda287af147f5a6aa07dbfc794", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dbfa0dd868b40d631dd23479d12a456b0ff1523c6fea8bbfc3028714d09f062e2aff7ca73adb7b1a2db949fad7981a4422d57da6a87c8de24064525ec1effc72", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1331ea68c6573f96a21c0649caa937cfc5db70fa8683f7a5656e88de7dcebb41fcf4a8842be4f773448b3858ead9d41fffea182b6118b06025c7b623f4861f5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5cc1d93e42f7c9b25814134e4b6106a743b3e2deb5a770704d27be4495deb2b37ef01bbec929c091d0fb555db2459c2eca0094a167d48b4b978b28dd003d48ce"} 02:34:45 INFO - Running post-run listener: copy_logs_to_upload_dir 02:34:45 INFO - Copying logs to upload dir... 02:34:45 INFO - mkdir: /builds/slave/test/build/upload/logs 02:34:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1255.400028 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2017-04-24 02:34:45.105516) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 02:34:45.108621) ========= 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-1493021468.957087-1264642343 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7c3d590c4590e31f23ec88a51504b1816069f1f2b0724d2d2fac717dd259d18134098e56d008db9caff80379f43c7d1ca96042bda287af147f5a6aa07dbfc794", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dbfa0dd868b40d631dd23479d12a456b0ff1523c6fea8bbfc3028714d09f062e2aff7ca73adb7b1a2db949fad7981a4422d57da6a87c8de24064525ec1effc72", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1331ea68c6573f96a21c0649caa937cfc5db70fa8683f7a5656e88de7dcebb41fcf4a8842be4f773448b3858ead9d41fffea182b6118b06025c7b623f4861f5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5cc1d93e42f7c9b25814134e4b6106a743b3e2deb5a770704d27be4495deb2b37ef01bbec929c091d0fb555db2459c2eca0094a167d48b4b978b28dd003d48ce"} build_url:https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011240 build_url: 'https://queue.taskcluster.net/v1/task/QS-XteUnTw23ach84BtEig/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7c3d590c4590e31f23ec88a51504b1816069f1f2b0724d2d2fac717dd259d18134098e56d008db9caff80379f43c7d1ca96042bda287af147f5a6aa07dbfc794", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/dbfa0dd868b40d631dd23479d12a456b0ff1523c6fea8bbfc3028714d09f062e2aff7ca73adb7b1a2db949fad7981a4422d57da6a87c8de24064525ec1effc72", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1331ea68c6573f96a21c0649caa937cfc5db70fa8683f7a5656e88de7dcebb41fcf4a8842be4f773448b3858ead9d41fffea182b6118b06025c7b623f4861f5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5cc1d93e42f7c9b25814134e4b6106a743b3e2deb5a770704d27be4495deb2b37ef01bbec929c091d0fb555db2459c2eca0094a167d48b4b978b28dd003d48ce"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 02:34:45.134001) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:34:45.134331) ========= 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-1493021468.957087-1264642343 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 02:34:45.220248) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 02:34:45.220562) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 02:34:45.220857) ========= ========= Total master_lag: 0.18 =========