builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-060 starttime: 1493109650.31 results: success (0) revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:50.309192) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:50.309642) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:50.309912) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:50.324728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:50.325031) ========= 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=337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-25 01:40:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 69.4M=0s 2017-04-25 01:40:50 (69.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123968 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:50.463918) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:50.464376) ========= 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=337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017603 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:50.513983) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-25 01:40:50.514325) ========= 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=337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-25 01:40:50,589 truncating revision to first 12 chars 2017-04-25 01:40:50,590 Setting DEBUG logging. 2017-04-25 01:40:50,590 attempt 1/10 2017-04-25 01:40:50,590 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-25 01:40:51,644 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.337026 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-25 01:40:51.865735) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:51.866175) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:51.866592) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:51.866890) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-25 01:40:51.877754) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-04-25 01:40:51.878184) ========= /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=337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:40:52 INFO - MultiFileLogger online at 20170425 01:40:52 in /builds/slave/test 01:40:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 01:40:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:40:52 INFO - {'append_to_log': False, 01:40:52 INFO - 'base_work_dir': '/builds/slave/test', 01:40:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 01:40:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:40:52 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 01:40:52 INFO - 'buildbot_json_path': 'buildprops.json', 01:40:52 INFO - 'config_files': ('talos/linux_config.py',), 01:40:52 INFO - 'default_actions': ('clobber', 01:40:52 INFO - 'read-buildbot-config', 01:40:52 INFO - 'download-and-extract', 01:40:52 INFO - 'populate-webroot', 01:40:52 INFO - 'create-virtualenv', 01:40:52 INFO - 'install', 01:40:52 INFO - 'run-tests'), 01:40:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:40:52 INFO - 'download_minidump_stackwalk': True, 01:40:52 INFO - 'download_symbols': 'ondemand', 01:40:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:40:52 INFO - 'tooltool.py': '/tools/tooltool.py', 01:40:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:40:52 INFO - '/tools/misc-python/virtualenv.py')}, 01:40:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:40:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:40:52 INFO - 'gecko_profile': False, 01:40:52 INFO - 'gecko_profile_interval': 0, 01:40:52 INFO - 'installer_path': 'installer.exe', 01:40:52 INFO - 'log_level': 'info', 01:40:52 INFO - 'log_name': 'talos', 01:40:52 INFO - 'log_to_console': True, 01:40:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:40:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:40:52 INFO - 'opt_config_files': (), 01:40:52 INFO - 'pip_index': False, 01:40:52 INFO - 'suite': 'dromaeojs', 01:40:52 INFO - 'system_bits': '32', 01:40:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:40:52 INFO - 'title': 'talos-linux64-ix-060', 01:40:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:40:52 INFO - 'use_talos_json': True, 01:40:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:40:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:40:52 INFO - 'work_dir': 'build'} 01:40:52 INFO - [mozharness: 2017-04-25 08:40:52.057167Z] Running clobber step. 01:40:52 INFO - Running pre-action listener: _resource_record_pre_action 01:40:52 INFO - Running main action method: clobber 01:40:52 INFO - rmtree: /builds/slave/test/build 01:40:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:40:52 INFO - Running post-action listener: _resource_record_post_action 01:40:52 INFO - [mozharness: 2017-04-25 08:40:52.989697Z] Finished clobber step (success) 01:40:52 INFO - [mozharness: 2017-04-25 08:40:52.989796Z] Running read-buildbot-config step. 01:40:52 INFO - Running pre-action listener: _resource_record_pre_action 01:40:52 INFO - Running main action method: read_buildbot_config 01:40:52 INFO - Using buildbot properties: 01:40:52 INFO - { 01:40:52 INFO - "project": "", 01:40:52 INFO - "product": "firefox", 01:40:52 INFO - "who": "nobody@mozilla.org", 01:40:52 INFO - "installer_path": "public/build/target.tar.bz2", 01:40:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 01:40:52 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora", 01:40:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 01:40:52 INFO - "stage_platform": "linux64", 01:40:52 INFO - "basedir": "/builds/slave/test", 01:40:52 INFO - "buildnumber": 140, 01:40:52 INFO - "platform": "ubuntu64_hw", 01:40:52 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:40:52 INFO - "slavebuilddir": "test", 01:40:52 INFO - "taskId": "W3ogDw30Q5igJqsmJt1OOg", 01:40:52 INFO - "branch": "mozilla-aurora", 01:40:52 INFO - "script_repo_revision": "production", 01:40:52 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:40:52 INFO - "slavename": "talos-linux64-ix-060", 01:40:52 INFO - "repo_path": "releases/mozilla-aurora" 01:40:52 INFO - } 01:40:52 INFO - Finding installer, test and symbols from parent task. 01:40:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W3ogDw30Q5igJqsmJt1OOg'}, attempt #1 01:40:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W3ogDw30Q5igJqsmJt1OOg'}, attempt #1 01:40:53 INFO - Task dependencies: GnHRU3prQySfDyuhggf2uA 01:40:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA'}, attempt #1 01:40:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.tar.bz2 01:40:54 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/test_packages.json 01:40:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.crashreporter-symbols.zip 01:40:54 INFO - Running post-action listener: _resource_record_post_action 01:40:54 INFO - [mozharness: 2017-04-25 08:40:54.451162Z] Finished read-buildbot-config step (success) 01:40:54 INFO - [mozharness: 2017-04-25 08:40:54.451382Z] Running download-and-extract step. 01:40:54 INFO - Running pre-action listener: _resource_record_pre_action 01:40:54 INFO - Running main action method: download_and_extract 01:40:54 INFO - mkdir: /builds/slave/test/build/tests 01:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:54 INFO - trying https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.test_packages.json 01:40:54 INFO - Downloading https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:40:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:40:54 INFO - Downloaded 896 bytes. 01:40:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:40:55 INFO - Using the following test package requirements: 01:40:55 INFO - {u'common': [u'target.common.tests.zip'], 01:40:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:40:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:40:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:40:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:40:55 INFO - u'mozbase': [u'target.common.tests.zip'], 01:40:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:40:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:40:55 INFO - u'web-platform': [u'target.common.tests.zip', 01:40:55 INFO - u'target.web-platform.tests.zip'], 01:40:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:40:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:40:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.common.tests.zip 01:40:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:40:55 INFO - Fetch https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.common.tests.zip into memory 01:40:56 INFO - Expected file size: 59541556 01:40:56 INFO - Obtained file size: 59541556 01:41:05 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:41:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.common.tests.zip 01:41:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:41:05 INFO - Fetch https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.common.tests.zip into memory 01:41:06 INFO - Expected file size: 59541556 01:41:06 INFO - Obtained file size: 59541556 01:41:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.talos.tests.zip 01:41:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:41:17 INFO - Fetch https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.talos.tests.zip into memory 01:41:18 INFO - Expected file size: 14404424 01:41:18 INFO - Obtained file size: 14404424 01:41: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')]} 01:41:18 INFO - trying https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.tar.bz2 01:41:18 INFO - Downloading https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:41:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:41:19 INFO - Downloaded 66778196 bytes. 01:41:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.tar.bz2 01:41:19 INFO - mkdir: /builds/slave/test/properties 01:41:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:41:19 INFO - Writing to file /builds/slave/test/properties/build_url 01:41:19 INFO - Contents: 01:41:19 INFO - build_url:https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.tar.bz2 01:41:19 INFO - Running post-action listener: _resource_record_post_action 01:41:19 INFO - Running post-action listener: set_extra_try_arguments 01:41:19 INFO - [mozharness: 2017-04-25 08:41:19.514454Z] Finished download-and-extract step (success) 01:41:19 INFO - [mozharness: 2017-04-25 08:41:19.514550Z] Running populate-webroot step. 01:41:19 INFO - Running pre-action listener: _resource_record_pre_action 01:41:19 INFO - Running main action method: populate_webroot 01:41:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:41:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:41:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:41:19 INFO - '--tppagecycles', 01:41:19 INFO - '7'], 01:41:19 INFO - 'tests': ['tsvgm']}}, 01:41:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:41:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:41:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:41:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:41:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:41:19 INFO - 'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:41:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:41:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:41:19 INFO - 'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['damp', 'tps']}, 01:41:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:41:19 INFO - 'tests': ['damp', 'tps']}, 01:41:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['dromaeo_dom']}, 01:41:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:41:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['basic_compositor_video']}, 01:41:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:41:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['a11yr', 01:41:19 INFO - 'ts_paint', 01:41:19 INFO - 'tpaint', 01:41:19 INFO - 'sessionrestore', 01:41:19 INFO - 'sessionrestore_no_auto_restore', 01:41:19 INFO - 'tabpaint']}, 01:41:19 INFO - 'other-e10s': {'tests': ['a11yr', 01:41:19 INFO - 'ts_paint', 01:41:19 INFO - 'tpaint', 01:41:19 INFO - 'sessionrestore', 01:41:19 INFO - 'sessionrestore_no_auto_restore', 01:41:19 INFO - 'tabpaint']}, 01:41:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:41:19 INFO - 'ts_paint', 01:41:19 INFO - 'tpaint', 01:41:19 INFO - 'sessionrestore', 01:41:19 INFO - 'sessionrestore_no_auto_restore', 01:41:19 INFO - 'tabpaint']}, 01:41:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:41:19 INFO - 'ts_paint', 01:41:19 INFO - 'tpaint', 01:41:19 INFO - 'sessionrestore', 01:41:19 INFO - 'sessionrestore_no_auto_restore', 01:41:19 INFO - 'tabpaint']}, 01:41:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['a11yr', 01:41:19 INFO - 'ts_paint', 01:41:19 INFO - 'tpaint', 01:41:19 INFO - 'sessionrestore', 01:41:19 INFO - 'sessionrestore_no_auto_restore', 01:41:19 INFO - 'tabpaint']}, 01:41:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['a11yr', 01:41:19 INFO - 'ts_paint', 01:41:19 INFO - 'tpaint', 01:41:19 INFO - 'sessionrestore', 01:41:19 INFO - 'sessionrestore_no_auto_restore', 01:41:19 INFO - 'tabpaint']}, 01:41:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['tsvgx', 01:41:19 INFO - 'tsvgr_opacity', 01:41:19 INFO - 'tart', 01:41:19 INFO - 'tscrollx', 01:41:19 INFO - 'cart', 01:41:19 INFO - 'tsvg_static']}, 01:41:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:41:19 INFO - 'tsvgr_opacity', 01:41:19 INFO - 'tart', 01:41:19 INFO - 'tscrollx', 01:41:19 INFO - 'cart', 01:41:19 INFO - 'tsvg_static']}, 01:41:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:41:19 INFO - 'talos_options': ['--disable-e10s'], 01:41:19 INFO - 'tests': ['tp5o']}, 01:41:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:41:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:41:19 INFO - 'talos_options': ['--disable-e10s', 01:41:19 INFO - '--xperf_path', 01:41:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:19 INFO - 'tests': ['tp5n']}, 01:41:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:41:19 INFO - 'talos_options': ['--xperf_path', 01:41:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:41:19 INFO - 'tests': ['tp5n']}}, 01:41:19 INFO - 'talos.zip': {'path': '', 01:41:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:41:19 INFO - Running post-action listener: _resource_record_post_action 01:41:19 INFO - [mozharness: 2017-04-25 08:41:19.527662Z] Finished populate-webroot step (success) 01:41:19 INFO - [mozharness: 2017-04-25 08:41:19.527752Z] Running create-virtualenv step. 01:41:19 INFO - Running pre-action listener: _resource_record_pre_action 01:41:19 INFO - Running main action method: create_virtualenv 01:41:19 INFO - Creating virtualenv /builds/slave/test/build/venv 01:41:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:41:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:41:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:41:19 INFO - Using real prefix '/usr' 01:41:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:41:20 INFO - Installing distribute.............................................................................................................................................................................................done. 01:41:22 INFO - Installing pip.................done. 01:41:22 INFO - Return code: 0 01:41:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:41:22 INFO - Reading from file tmpfile_stdout 01:41:22 INFO - Output received: 01:41:22 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 01:41:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:22 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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:41:22 INFO - Using env: {'DISPLAY': ':0', 01:41:22 INFO - 'HOME': '/home/cltbld', 01:41:22 INFO - 'LANG': 'en_US.UTF-8', 01:41:22 INFO - 'LANGUAGE': 'en_US:en', 01:41:22 INFO - 'LOGNAME': 'cltbld', 01:41:22 INFO - 'MAIL': '/var/mail/cltbld', 01:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:22 INFO - 'MOZ_NO_REMOTE': '1', 01:41:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:22 INFO - 'NO_EM_RESTART': '1', 01:41:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:22 INFO - 'PWD': '/builds/slave/test', 01:41:22 INFO - 'SHELL': '/bin/bash', 01:41:22 INFO - 'SHLVL': '1', 01:41:22 INFO - 'TERM': 'linux', 01:41:22 INFO - 'TMOUT': '86400', 01:41:22 INFO - 'USER': 'cltbld', 01:41:22 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:22 INFO - '_': '/tools/buildbot/bin/python'} 01:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:41:22 INFO - Cleaning up... 01:41:22 INFO - Return code: 0 01:41:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:22 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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:41:22 INFO - Using env: {'DISPLAY': ':0', 01:41:22 INFO - 'HOME': '/home/cltbld', 01:41:22 INFO - 'LANG': 'en_US.UTF-8', 01:41:22 INFO - 'LANGUAGE': 'en_US:en', 01:41:22 INFO - 'LOGNAME': 'cltbld', 01:41:22 INFO - 'MAIL': '/var/mail/cltbld', 01:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:22 INFO - 'MOZ_NO_REMOTE': '1', 01:41:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:22 INFO - 'NO_EM_RESTART': '1', 01:41:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:22 INFO - 'PWD': '/builds/slave/test', 01:41:22 INFO - 'SHELL': '/bin/bash', 01:41:22 INFO - 'SHLVL': '1', 01:41:22 INFO - 'TERM': 'linux', 01:41:22 INFO - 'TMOUT': '86400', 01:41:22 INFO - 'USER': 'cltbld', 01:41:22 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:22 INFO - '_': '/tools/buildbot/bin/python'} 01:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:22 INFO - Downloading/unpacking psutil>=3.1.1 01:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:41:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:24 INFO - Installing collected packages: psutil 01:41:24 INFO - Running setup.py install for psutil 01:41:24 INFO - building 'psutil._psutil_linux' extension 01:41:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 01:41:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 01:41:25 INFO - building 'psutil._psutil_posix' extension 01:41:25 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 01:41:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 01:41:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:41:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:41:25 INFO - Successfully installed psutil 01:41:25 INFO - Cleaning up... 01:41:25 INFO - Return code: 0 01:41:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:25 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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:41:25 INFO - Using env: {'DISPLAY': ':0', 01:41:25 INFO - 'HOME': '/home/cltbld', 01:41:25 INFO - 'LANG': 'en_US.UTF-8', 01:41:25 INFO - 'LANGUAGE': 'en_US:en', 01:41:25 INFO - 'LOGNAME': 'cltbld', 01:41:25 INFO - 'MAIL': '/var/mail/cltbld', 01:41:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:25 INFO - 'MOZ_NO_REMOTE': '1', 01:41:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:25 INFO - 'NO_EM_RESTART': '1', 01:41:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:25 INFO - 'PWD': '/builds/slave/test', 01:41:25 INFO - 'SHELL': '/bin/bash', 01:41:25 INFO - 'SHLVL': '1', 01:41:25 INFO - 'TERM': 'linux', 01:41:25 INFO - 'TMOUT': '86400', 01:41:25 INFO - 'USER': 'cltbld', 01:41:25 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:25 INFO - '_': '/tools/buildbot/bin/python'} 01:41:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:41:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:41:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:41:27 INFO - Installing collected packages: mozsystemmonitor 01:41:27 INFO - Running setup.py install for mozsystemmonitor 01:41:27 INFO - Successfully installed mozsystemmonitor 01:41:27 INFO - Cleaning up... 01:41:27 INFO - Return code: 0 01:41:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41: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', '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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:41: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 jsonschema==2.5.1 01:41:27 INFO - Using env: {'DISPLAY': ':0', 01:41:27 INFO - 'HOME': '/home/cltbld', 01:41:27 INFO - 'LANG': 'en_US.UTF-8', 01:41:27 INFO - 'LANGUAGE': 'en_US:en', 01:41:27 INFO - 'LOGNAME': 'cltbld', 01:41:27 INFO - 'MAIL': '/var/mail/cltbld', 01:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:27 INFO - 'MOZ_NO_REMOTE': '1', 01:41:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:27 INFO - 'NO_EM_RESTART': '1', 01:41:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:27 INFO - 'PWD': '/builds/slave/test', 01:41:27 INFO - 'SHELL': '/bin/bash', 01:41:27 INFO - 'SHLVL': '1', 01:41:27 INFO - 'TERM': 'linux', 01:41:27 INFO - 'TMOUT': '86400', 01:41:27 INFO - 'USER': 'cltbld', 01:41:27 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:27 INFO - '_': '/tools/buildbot/bin/python'} 01:41:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:28 INFO - Downloading/unpacking jsonschema==2.5.1 01:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:41:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:30 INFO - Downloading functools32-3.2.3-2.tar.gz 01:41:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:41:30 INFO - warning: no files found matching '*.txt' 01:41:30 INFO - no previously-included directories found matching 'build' 01:41:30 INFO - no previously-included directories found matching 'dist' 01:41:30 INFO - no previously-included directories found matching '.git*' 01:41:30 INFO - Installing collected packages: jsonschema, functools32 01:41:30 INFO - Running setup.py install for functools32 01:41:30 INFO - warning: no files found matching '*.txt' 01:41:30 INFO - no previously-included directories found matching 'build' 01:41:30 INFO - no previously-included directories found matching 'dist' 01:41:30 INFO - no previously-included directories found matching '.git*' 01:41:30 INFO - Successfully installed jsonschema functools32 01:41:30 INFO - Cleaning up... 01:41:30 INFO - Return code: 0 01:41:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:30 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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:41:30 INFO - Using env: {'DISPLAY': ':0', 01:41:30 INFO - 'HOME': '/home/cltbld', 01:41:30 INFO - 'LANG': 'en_US.UTF-8', 01:41:30 INFO - 'LANGUAGE': 'en_US:en', 01:41:30 INFO - 'LOGNAME': 'cltbld', 01:41:30 INFO - 'MAIL': '/var/mail/cltbld', 01:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:30 INFO - 'MOZ_NO_REMOTE': '1', 01:41:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:30 INFO - 'NO_EM_RESTART': '1', 01:41:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:30 INFO - 'PWD': '/builds/slave/test', 01:41:30 INFO - 'SHELL': '/bin/bash', 01:41:30 INFO - 'SHLVL': '1', 01:41:30 INFO - 'TERM': 'linux', 01:41:30 INFO - 'TMOUT': '86400', 01:41:30 INFO - 'USER': 'cltbld', 01:41:30 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:30 INFO - '_': '/tools/buildbot/bin/python'} 01:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:41:30 INFO - Cleaning up... 01:41:30 INFO - Return code: 0 01:41:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:30 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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:41:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:41:30 INFO - Using env: {'DISPLAY': ':0', 01:41:30 INFO - 'HOME': '/home/cltbld', 01:41:30 INFO - 'LANG': 'en_US.UTF-8', 01:41:30 INFO - 'LANGUAGE': 'en_US:en', 01:41:30 INFO - 'LOGNAME': 'cltbld', 01:41:30 INFO - 'MAIL': '/var/mail/cltbld', 01:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:30 INFO - 'MOZ_NO_REMOTE': '1', 01:41:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:30 INFO - 'NO_EM_RESTART': '1', 01:41:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:30 INFO - 'PWD': '/builds/slave/test', 01:41:30 INFO - 'SHELL': '/bin/bash', 01:41:30 INFO - 'SHLVL': '1', 01:41:30 INFO - 'TERM': 'linux', 01:41:30 INFO - 'TMOUT': '86400', 01:41:30 INFO - 'USER': 'cltbld', 01:41:30 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:30 INFO - '_': '/tools/buildbot/bin/python'} 01:41:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:31 INFO - Downloading/unpacking blobuploader==1.2.4 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41: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 01:41:33 INFO - Downloading blobuploader-1.2.4.tar.gz 01:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:41:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:41:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:33 INFO - Downloading docopt-0.6.1.tar.gz 01:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:41:33 INFO - Installing collected packages: blobuploader, requests, docopt 01:41:33 INFO - Running setup.py install for blobuploader 01:41:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:41:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:41:33 INFO - Running setup.py install for requests 01:41:34 INFO - Running setup.py install for docopt 01:41:34 INFO - Successfully installed blobuploader requests docopt 01:41:34 INFO - Cleaning up... 01:41:34 INFO - Return code: 0 01:41:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:34 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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:41:34 INFO - Using env: {'DISPLAY': ':0', 01:41:34 INFO - 'HOME': '/home/cltbld', 01:41:34 INFO - 'LANG': 'en_US.UTF-8', 01:41:34 INFO - 'LANGUAGE': 'en_US:en', 01:41:34 INFO - 'LOGNAME': 'cltbld', 01:41:34 INFO - 'MAIL': '/var/mail/cltbld', 01:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:34 INFO - 'MOZ_NO_REMOTE': '1', 01:41:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:34 INFO - 'NO_EM_RESTART': '1', 01:41:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:34 INFO - 'PWD': '/builds/slave/test', 01:41:34 INFO - 'SHELL': '/bin/bash', 01:41:34 INFO - 'SHLVL': '1', 01:41:34 INFO - 'TERM': 'linux', 01:41:34 INFO - 'TMOUT': '86400', 01:41:34 INFO - 'USER': 'cltbld', 01:41:34 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:34 INFO - '_': '/tools/buildbot/bin/python'} 01:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:34 INFO - Running setup.py (path:/tmp/pip-WGd3dQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:34 INFO - Running setup.py (path:/tmp/pip-J8ytRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:34 INFO - Running setup.py (path:/tmp/pip-RSu1K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:34 INFO - Running setup.py (path:/tmp/pip-X10ry6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:35 INFO - Running setup.py (path:/tmp/pip-3ylnws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:35 INFO - Running setup.py (path:/tmp/pip-8ihb4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:35 INFO - Running setup.py (path:/tmp/pip-YxNQcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:35 INFO - Running setup.py (path:/tmp/pip-Tk6suK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:35 INFO - Running setup.py (path:/tmp/pip-lNv7av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:35 INFO - Running setup.py (path:/tmp/pip-U_PjN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:35 INFO - Running setup.py (path:/tmp/pip-6fBFPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:35 INFO - Running setup.py (path:/tmp/pip-lJzNvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:35 INFO - Running setup.py (path:/tmp/pip-lCYP_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:35 INFO - Running setup.py (path:/tmp/pip-OxuLF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:35 INFO - Running setup.py (path:/tmp/pip-PbYDHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:35 INFO - Running setup.py (path:/tmp/pip-WawoHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:35 INFO - Running setup.py (path:/tmp/pip-Pf3fFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:41:35 INFO - Running setup.py install for manifestparser 01:41:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozcrash 01:41:36 INFO - Running setup.py install for mozdebug 01:41:36 INFO - Running setup.py install for mozdevice 01:41:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozfile 01:41:36 INFO - Running setup.py install for mozhttpd 01:41:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozinfo 01:41:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozInstall 01:41:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for mozleak 01:41:36 INFO - Running setup.py install for mozlog 01:41:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:41:36 INFO - Running setup.py install for moznetwork 01:41:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Running setup.py install for mozprocess 01:41:37 INFO - Running setup.py install for mozprofile 01:41:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Running setup.py install for mozrunner 01:41:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Running setup.py install for mozscreenshot 01:41:37 INFO - Running setup.py install for moztest 01:41:37 INFO - Running setup.py install for mozversion 01:41:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:41:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:41:37 INFO - Cleaning up... 01:41:37 INFO - Return code: 0 01:41:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:37 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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:41:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:41:37 INFO - Using env: {'DISPLAY': ':0', 01:41:37 INFO - 'HOME': '/home/cltbld', 01:41:37 INFO - 'LANG': 'en_US.UTF-8', 01:41:37 INFO - 'LANGUAGE': 'en_US:en', 01:41:37 INFO - 'LOGNAME': 'cltbld', 01:41:37 INFO - 'MAIL': '/var/mail/cltbld', 01:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:37 INFO - 'MOZ_NO_REMOTE': '1', 01:41:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:37 INFO - 'NO_EM_RESTART': '1', 01:41:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:37 INFO - 'PWD': '/builds/slave/test', 01:41:37 INFO - 'SHELL': '/bin/bash', 01:41:37 INFO - 'SHLVL': '1', 01:41:37 INFO - 'TERM': 'linux', 01:41:37 INFO - 'TMOUT': '86400', 01:41:37 INFO - 'USER': 'cltbld', 01:41:37 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:37 INFO - '_': '/tools/buildbot/bin/python'} 01:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:41:37 INFO - Running setup.py (path:/tmp/pip-OCZejA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:41:38 INFO - Running setup.py (path:/tmp/pip-5x8s4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:41:38 INFO - Running setup.py (path:/tmp/pip-ThSw3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:41:38 INFO - Running setup.py (path:/tmp/pip-m2K3Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:41:38 INFO - Running setup.py (path:/tmp/pip-zwTaXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:41:38 INFO - Running setup.py (path:/tmp/pip-9izxT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:41:38 INFO - Running setup.py (path:/tmp/pip-7O1zn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:41:38 INFO - Running setup.py (path:/tmp/pip-_nydo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:41:38 INFO - Running setup.py (path:/tmp/pip-Ebne7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:41:38 INFO - Running setup.py (path:/tmp/pip-Q8Ri_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:41:38 INFO - Running setup.py (path:/tmp/pip-EwFmXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:41:38 INFO - Running setup.py (path:/tmp/pip-eet8ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:41:38 INFO - Running setup.py (path:/tmp/pip-nQYvXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:41:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:41:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:41:38 INFO - Running setup.py (path:/tmp/pip-BmnHYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:39 INFO - Running setup.py (path:/tmp/pip-MuBD5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:41:39 INFO - Running setup.py (path:/tmp/pip-1NGmcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:41:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:41:39 INFO - Running setup.py (path:/tmp/pip-V_kKMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:41:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:41:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:41:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:41:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:41:41 INFO - Downloading blessings-1.6.tar.gz 01:41:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:41:41 INFO - Installing collected packages: blessings 01:41:41 INFO - Running setup.py install for blessings 01:41:41 INFO - Successfully installed blessings 01:41:41 INFO - Cleaning up... 01:41:41 INFO - Return code: 0 01:41:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:41:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:41 INFO - Reading from file tmpfile_stdout 01:41:41 INFO - Current package versions: 01:41:41 INFO - argparse == 1.2.1 01:41:41 INFO - blessings == 1.6 01:41:41 INFO - blobuploader == 1.2.4 01:41:41 INFO - docopt == 0.6.1 01:41:41 INFO - functools32 == 3.2.3-2 01:41:41 INFO - jsonschema == 2.5.1 01:41:41 INFO - manifestparser == 1.1 01:41:41 INFO - mozInstall == 1.12 01:41:41 INFO - mozcrash == 1.0 01:41:41 INFO - mozdebug == 0.1 01:41:41 INFO - mozdevice == 0.49 01:41:41 INFO - mozfile == 1.2 01:41:41 INFO - mozhttpd == 0.7 01:41:41 INFO - mozinfo == 0.9 01:41:41 INFO - mozleak == 0.1 01:41:41 INFO - mozlog == 3.4 01:41:41 INFO - moznetwork == 0.27 01:41:41 INFO - mozprocess == 0.25 01:41:41 INFO - mozprofile == 0.28 01:41:41 INFO - mozrunner == 6.13 01:41:41 INFO - mozscreenshot == 0.1 01:41:41 INFO - mozsystemmonitor == 0.3 01:41:41 INFO - moztest == 0.8 01:41:41 INFO - mozversion == 1.4 01:41:41 INFO - psutil == 3.1.1 01:41:41 INFO - requests == 1.2.3 01:41:41 INFO - wsgiref == 0.1.2 01:41:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:41 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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:41:41 INFO - Using env: {'DISPLAY': ':0', 01:41:41 INFO - 'HOME': '/home/cltbld', 01:41:41 INFO - 'LANG': 'en_US.UTF-8', 01:41:41 INFO - 'LANGUAGE': 'en_US:en', 01:41:41 INFO - 'LOGNAME': 'cltbld', 01:41:41 INFO - 'MAIL': '/var/mail/cltbld', 01:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:41 INFO - 'MOZ_NO_REMOTE': '1', 01:41:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:41 INFO - 'NO_EM_RESTART': '1', 01:41:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:41 INFO - 'PWD': '/builds/slave/test', 01:41:41 INFO - 'SHELL': '/bin/bash', 01:41:41 INFO - 'SHLVL': '1', 01:41:41 INFO - 'TERM': 'linux', 01:41:41 INFO - 'TMOUT': '86400', 01:41:41 INFO - 'USER': 'cltbld', 01:41:41 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:41 INFO - '_': '/tools/buildbot/bin/python'} 01:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:41:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:41:41 INFO - Cleaning up... 01:41:41 INFO - Return code: 0 01:41:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:41:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:41:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:41:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x27e91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2827a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a87310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, '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': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:41:41 INFO - Using env: {'DISPLAY': ':0', 01:41:41 INFO - 'HOME': '/home/cltbld', 01:41:41 INFO - 'LANG': 'en_US.UTF-8', 01:41:41 INFO - 'LANGUAGE': 'en_US:en', 01:41:41 INFO - 'LOGNAME': 'cltbld', 01:41:41 INFO - 'MAIL': '/var/mail/cltbld', 01:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:41 INFO - 'MOZ_NO_REMOTE': '1', 01:41:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:41 INFO - 'NO_EM_RESTART': '1', 01:41:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:41 INFO - 'PWD': '/builds/slave/test', 01:41:41 INFO - 'SHELL': '/bin/bash', 01:41:41 INFO - 'SHLVL': '1', 01:41:41 INFO - 'TERM': 'linux', 01:41:41 INFO - 'TMOUT': '86400', 01:41:41 INFO - 'USER': 'cltbld', 01:41:41 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:41 INFO - '_': '/tools/buildbot/bin/python'} 01:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:41:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:41:42 INFO - Cleaning up... 01:41:42 INFO - Return code: 0 01:41:42 INFO - Running post-action listener: _resource_record_post_action 01:41:42 INFO - Running post-action listener: _start_resource_monitoring 01:41:42 INFO - Starting resource monitoring. 01:41:42 INFO - [mozharness: 2017-04-25 08:41:42.169618Z] Finished create-virtualenv step (success) 01:41:42 INFO - [mozharness: 2017-04-25 08:41:42.170091Z] Running install step. 01:41:42 INFO - Running pre-action listener: _resource_record_pre_action 01:41:42 INFO - Running main action method: install 01:41:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:41:42 INFO - Reading from file tmpfile_stdout 01:41:42 INFO - Detecting whether we're running mozinstall >=1.0... 01:41:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:41:42 INFO - Reading from file tmpfile_stdout 01:41:42 INFO - Output received: 01:41:42 INFO - Usage: mozinstall [options] installer 01:41:42 INFO - Options: 01:41:42 INFO - -h, --help show this help message and exit 01:41:42 INFO - -d DEST, --destination=DEST 01:41:42 INFO - Directory to install application into. [default: 01:41:42 INFO - "/builds/slave/test"] 01:41:42 INFO - --app=APP Application being installed. [default: firefox] 01:41:42 INFO - mkdir: /builds/slave/test/build/application 01:41:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:41:59 INFO - Reading from file tmpfile_stdout 01:41:59 INFO - Output received: 01:41:59 INFO - /builds/slave/test/build/application/firefox/firefox 01:41:59 INFO - Running post-action listener: _resource_record_post_action 01:41:59 INFO - [mozharness: 2017-04-25 08:41:59.115819Z] Finished install step (success) 01:41:59 INFO - [mozharness: 2017-04-25 08:41:59.116067Z] Running run-tests step. 01:41:59 INFO - Running pre-action listener: _resource_record_pre_action 01:41:59 INFO - Running main action method: run_tests 01:41:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:41:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:41:59 INFO - Python 2.7.3 01:41:59 INFO - Return code: 0 01:41:59 INFO - grabbing minidump binary from tooltool 01:41:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:41:59 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 0x2a84400>, '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 0x2a853b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a85840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:41:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:41:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 01:41:59 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:41:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:41:59 INFO - Return code: 0 01:41:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:41:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:41:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:41:59 INFO - ENV: RUST_BACKTRACE is now 1 01:41:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:41:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:41:59 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/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:41:59 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/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-060 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:41:59 INFO - Using env: {'DISPLAY': ':0', 01:41:59 INFO - 'HOME': '/home/cltbld', 01:41:59 INFO - 'LANG': 'en_US.UTF-8', 01:41:59 INFO - 'LANGUAGE': 'en_US:en', 01:41:59 INFO - 'LOGNAME': 'cltbld', 01:41:59 INFO - 'MAIL': '/var/mail/cltbld', 01:41:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:41:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:41:59 INFO - 'MOZ_NO_REMOTE': '1', 01:41:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:41:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:41:59 INFO - 'NO_EM_RESTART': '1', 01:41:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:41:59 INFO - 'PWD': '/builds/slave/test', 01:41:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:41:59 INFO - 'RUST_BACKTRACE': '1', 01:41:59 INFO - 'SHELL': '/bin/bash', 01:41:59 INFO - 'SHLVL': '1', 01:41:59 INFO - 'TERM': 'linux', 01:41:59 INFO - 'TMOUT': '86400', 01:41:59 INFO - 'USER': 'cltbld', 01:41:59 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763', 01:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:41:59 INFO - '_': '/tools/buildbot/bin/python'} 01:41:59 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/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:41:59 INFO - mozversion application_buildid: 20170425074731 01:41:59 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:41:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:41:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:41:59 INFO - mozversion application_name: Firefox 01:41:59 INFO - mozversion application_remotingname: firefox-dev 01:41:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:41:59 INFO - mozversion application_vendor: Mozilla 01:41:59 INFO - mozversion application_version: 54.0a2 01:41:59 INFO - mozversion platform_buildid: 20170425074731 01:41:59 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:41:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:41:59 INFO - mozversion platform_version: 54.0a2 01:41:59 INFO - using testdate: 1493109719 01:41:59 INFO - actual date: 1493109719 01:41:59 INFO - starting webserver on 'localhost:41649' 01:41:59 INFO - SUITE-START | Running 2 tests 01:41:59 INFO - TEST-START | dromaeo_css 01:41:59 INFO - operating with platform_type : linux_ 01:41:59 INFO - Initialising browser for dromaeo_css test... 01:41:59 INFO - TEST-INFO | started process 19861 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvDZxq8/profile http://localhost:41649/getInfo.html) 01:42:03 INFO - PROCESS | 19861 | __metrics Screen width/height:1600/1200 01:42:03 INFO - PROCESS | 19861 | colorDepth:24 01:42:03 INFO - PROCESS | 19861 | Browser inner width/height: 1024/697 01:42:03 INFO - PROCESS | 19861 | __metrics 01:42:04 INFO - PROCESS | 19861 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:42:04 INFO - PROCESS | 19861 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:42:04 INFO - PROCESS | 19861 | console.error: PushService: 01:42:04 INFO - PROCESS | 19861 | stateChangeProcessEnqueue: Error transitioning state 01:42:04 INFO - PROCESS | 19861 | UnknownError 01:42:05 INFO - TEST-INFO | 19861: exit 0 01:42:05 INFO - Browser initialized. 01:42:05 INFO - Running cycle 1/1 for dromaeo_css test... 01:42:05 INFO - TEST-INFO | started process 19952 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvDZxq8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:42:17 INFO - PROCESS | 19952 | RSS: Main: 140218368 01:42:17 INFO - PROCESS | 19952 | 01:45:18 INFO - PROCESS | 19952 | Cycle 1(1): loaded http://localhost:41649/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41649/tests/dromaeo/cssquery-ext.html) 01:45:19 INFO - PROCESS | 19952 | RSS: Main: 287571968 01:45:19 INFO - PROCESS | 19952 | 01:47:07 INFO - PROCESS | 19952 | ************************* 01:47:07 INFO - PROCESS | 19952 | A coding exception was thrown and uncaught in a Task. 01:47:07 INFO - PROCESS | 19952 | 01:47:07 ERROR - PROCESS | 19952 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:47:07 INFO - PROCESS | 19952 | Full stack: 01:47:07 INFO - PROCESS | 19952 | ************************* 01:47:07 INFO - PROCESS | 19952 | ************************* 01:47:07 INFO - PROCESS | 19952 | A coding exception was thrown and uncaught in a Task. 01:47:07 INFO - PROCESS | 19952 | 01:47:07 ERROR - PROCESS | 19952 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:47:07 INFO - PROCESS | 19952 | Full stack: 01:47:07 INFO - PROCESS | 19952 | ************************* 01:47:07 INFO - PROCESS | 19952 | ************************* 01:47:07 INFO - PROCESS | 19952 | A coding exception was thrown and uncaught in a Task. 01:47:07 INFO - PROCESS | 19952 | 01:47:07 ERROR - PROCESS | 19952 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:47:07 INFO - PROCESS | 19952 | Full stack: 01:47:07 INFO - PROCESS | 19952 | ************************* 01:47:07 INFO - PROCESS | 19952 | ************************* 01:47:07 INFO - PROCESS | 19952 | A coding exception was thrown and uncaught in a Task. 01:47:07 INFO - PROCESS | 19952 | 01:47:07 ERROR - PROCESS | 19952 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:47:07 INFO - PROCESS | 19952 | Full stack: 01:47:07 INFO - PROCESS | 19952 | ************************* 01:48:22 INFO - PROCESS | 19952 | Cycle 1(1): loaded http://localhost:41649/tests/dromaeo/cssquery-ext.html (next: http://localhost:41649/tests/dromaeo/cssquery-jquery.html) 01:48:22 INFO - PROCESS | 19952 | RSS: Main: 220749824 01:48:22 INFO - PROCESS | 19952 | 01:51:24 INFO - PROCESS | 19952 | Cycle 1(1): loaded http://localhost:41649/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41649/tests/dromaeo/cssquery-mootools.html) 01:51:25 INFO - PROCESS | 19952 | RSS: Main: 201670656 01:51:25 INFO - PROCESS | 19952 | 01:54:27 INFO - PROCESS | 19952 | Cycle 1(1): loaded http://localhost:41649/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41649/tests/dromaeo/cssquery-prototype.html) 01:54:27 INFO - PROCESS | 19952 | RSS: Main: 170549248 01:54:27 INFO - PROCESS | 19952 | 01:57:30 INFO - PROCESS | 19952 | Cycle 1(1): loaded http://localhost:41649/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41649/tests/dromaeo/cssquery-yui.html) 01:57:30 INFO - PROCESS | 19952 | RSS: Main: 178925568 01:57:30 INFO - PROCESS | 19952 | 02:00:33 INFO - PROCESS | 19952 | Cycle 1(1): loaded http://localhost:41649/tests/dromaeo/cssquery-yui.html (next: http://localhost:41649/tests/dromaeo/cssquery-dojo.html) 02:00:33 INFO - PROCESS | 19952 | RSS: Main: 271511552 02:00:33 INFO - PROCESS | 19952 | 02:00:33 INFO - PROCESS | 19952 | __start_tp_report 02:00:33 INFO - PROCESS | 19952 | _x_x_mozilla_page_load 02:00:33 INFO - PROCESS | 19952 | _x_x_mozilla_page_load_details 02:00:33 INFO - PROCESS | 19952 | |i|pagename|runs| 02:00:33 INFO - PROCESS | 19952 | |0;dojo.html;3424.9657503424964;3430.3482338355725;3446.707029902457;3450.6204317525053;3486.930261394772;5739.741711622976;5753.079507278836;5779.62432441891;5782.942170578293;5805.419458054193;5823.3012038555435;5868.882622347561;5876.5004974577105;5883.323417806942;5886.764529418829;5192.065428222918;5228.973855130719;5241.213817927322;5243.659162154467;5288.497592728685;5057.822976195834;5094.133997220481;5281.8943621127555;5285.6300058995885;5289.761960711778;5161.148410512261;5162.199859021839;5245.4754524547625;5252.8161514347;5273.841784746463;5263.86840328991;5276.261323414725;5279.709615971139;5279.736013199363;5288.497592728685;5005.47442518534;5100.183970564729;5175.249588809639;5229.47705229474;5236;5674.858128546819;5724.341700704389;5740.655560666373;5795.449432303924;5799.246098007275;6225.377462253784;6308.779192728232;6309.621422714606;6316.905246421307;6323.494120470351;6323.7786677466065;6326.841828954313;6335.873282534323;6339.492840572743;6339.683015849189;5650.943490565083;5692.7153642318135;5816.011278082694;5839.649621022752;5935.495482884004;6424.903626445607;6425.807225783234;6432.195975503062;6437.67811609427;6439.678016099177;6144.5084393248435;6147.815565533041;6214.067889816473;6220.160278362363;6220.844478888064;6041.788537401258;6074.878502429927;6155.076738489262;6304.905426418607;6316.178896743486;6213;6256.968715156486;6274.2784579773;6274.466670332981;6284.717187726564;6017.969910150508;6044.032954727222;6046.455818976313;6092.51259899207;6270.153529273581;4642.558956899089;4729.834455794031;4747.881302967454;4755.120302744003;4762.928556071662;4136.855210067633;4145.606167414091;4176.644985176294;4214.072903961123;4231.767252801125;5938.346781853993;5976.372480889531;5991.820245392558;6017.789377371859;6024.367441418588;6237.313895471494;6251.093591429218;6261.373862613702;6267;6274.843128921722;5692.37383887772;5729.656220626693;5994.460498555064;6010.819675409744;6013.609115407485;5205.27126202332;5309.734513274321;5334.039872822929;5360.758765855546;5380.408155102936;6564.212294524592;6592.538522303393;6595.835104122435;6606.405423511811;6611.50413718973;6065.939340606537;6270.467010304175;6329.715162817776;6337.841553961188;6337.841553961188;6387.361263873761;6402.295747467681;6414.967925160531;6488.837779055561;6514.51141164424;6090.573659843769;6093.025115981422;6103.664298463449;6105;6120.908186377119;6202.937970620236;6228.479987317212;6292.087647291209;6293.905591416041;6303;5848.970755146197;5881.088431293157;6028.57799953999;6055.576109672457;6132.938670613236;6113.449789518787;6155.4767844731905;6166.691665416622;6171.290301615371;6198.6280823150755;5920.526357891445;6128.448439640454;6171.691415429121;6173.135760993554;6176.227971503562;6041.667708276087;6111.938880611315;6119.694015299128;6142.692865356625;6218;6042.969785151046;6074.240719910012;6077.392260773887;6081.057436097412;6113.29697084841 02:00:33 INFO - PROCESS | 19952 | |1;ext.html;748.6930358552991;826.504097541475;842.9283510901573;894.472261365794;906.0893801729261;30767.692323076764;31161.844190779044;33598;43250.78374608122;44547.55452445475;44369;44512.777436112854;44532.77733611336;44583.331250031195;44731.55268447314;9060.81878362434;9168.266538676924;9187.586558604879;9188.448693078437;9190.402623829463;7529.360004399633;7758.069031716202;10621.840672389882;10838.67483975482;10855.891441085567;10859.619913303037;10885.292455990375;10897.891021089807;10914.945425272863;10918;10811.18916081297;10859.565617375374;10883.020528152465;10884.401357925311;10904.61833835812;10690.679279621623;10767.784644307148;10777.191710621657;10778.892211077868;10778.946105269424;41954.37068443976;42026;42131;42183.15633687309;42274.15451690949;4219.029623186653;4252.213340531981;4259.424977628011;4337.06753048093;4406.625436837872;29674.85162574195;30075;30125.69874301251;30822.845885770428;31366.215844603837;3851.903702407434;3923.8626648067466;3926.175503144343;3932.1742434088874;4025.6175663311938;218177;218255;218285;218861;218902;53223.73388133034;53329;53367.46632533625;53668;53729.73135134299;53126.46873531293;53283.46716532785;53295.20057199145;53308.20037699438;53341.46658533443;16458.917705411637;16810.075445850594;16818.075005874794;16821.83178168227;16822.327106915585;11946.402679866145;14915.403383864741;14946.32741526634;14952.102873827387;14961.700765984619;12235.510579576896;13209.93395033038;13393.799093013613;13548.254845983565;13984.930075349761;5460.396138548539;5504.862378440571;5543.944560554342;5555.444455554493;5597.692126933058;1030.860833787444;1038.503763339821;1039.4802598700649;1052.952617132231;1061.4480470155477;27966;28393.43213135726;28643.427131457254;28674;28893.27766805847;1112.0436424674774;1112.8052590796578;1125.5666568371237;1139.7549526851767;1150.8618965724168;14962.775558366633;15084.698306034037;15150.848491515164;15215.771763423558;15380.230988450532;271584;275769;276530;276875;276912;55889.7205513978;55958.44041559614;56115.158272625944;56182.43817561854;56238.718806405705;1501.2643804535699;1555.3778488604648;1562.849961426501;1580.857722804953;1605.5103193526086;25557.872210639573;27365.45269094647;27480.58779118275;27590;27727.58408623832;43307.56692433036;43316.35025474684;43353.34969975516;43461.56538434701;43486.17339873025;262991;264806;268220;268549;268672;29977.250568735082;31305.217369565944;35696.821515892254;35766.46350304696;35884;38398;38417.80791096027;38705.225895482494;38766.80616597012;38799.418008730456;29568;29690.991062878238;29764.85117574485;29807.55288670715;29887.701122988492;29732.256693581967;29960.700392996663;30064.398712026075;30397;30633.387332252783;33801.15497112592;33861.830690846386;33935;34111.65888341085;34157.65842341545;28559.714402856538;28577;28718.138455846358;28734.425311494073;29139.854300728362;22466.663000054687;23427.297181084596;23506.294811155694;23619.881900590386;23827.285181444586 02:00:33 INFO - PROCESS | 19952 | |2;jquery.html;415.5595069226618;443.7027191781507;459.1574460864314;464.35222864104577;476.7139716170298;19480.123394447248;19701.605967880652;20071.498212544695;21451.892740536274;21690.783092169073;21197.576048479023;21360.893195534038;21592;21617.351479455643;21685.67471487929;6575.441087507567;6656.933430665692;6723.630200338979;6776.593404395752;6816.761413350534;4116.279651061067;6597.505187110962;6719.395254427101;6734.8989765153565;6745.156855393076;4381.233284175272;4404.713693609921;4788.9521104788855;7714.190010048948;7921.36629069673;7631.12242092161;7657;7717.72987945422;7732.1108072571815;7822.569758663271;7692.807679807994;7704.46068775186;7705.190954949706;7707.768766937001;7709.922900770976;26225;26860.731392686215;27047;27563.724362756315;27775.58336624952;3641.763285386468;3644.6355364463607;3696.537932758405;3757.248550289953;3787.886363409102;15656.21718914061;17149.742753858704;17429.38997135107;17899.642007159782;18383;2275.590393729128;2330.475642980333;2380.797632201265;2398.556267090594;2400.651905473714;119876.40061799722;122881;123250.38374808159;123445;124431.37784311021;15718.842811571738;20456.284030058876;21481.46296342604;22192.334229973127;22845.543089138126;44351;44483.777581111885;44631.55368446339;45105.32342014872;45354.54645453503;11584;11736.823947640793;11820.23168494045;11828.52685892572;11960.342181179945;9548.283878708986;9647.517624118766;9729.530231467865;9847.950760246153;9863.506824658738;11165;11362.318260904372;11514.76970460586;11582.536698531965;11680.035160971314;3924.6664033556895;4054.6958978076764;4254.361845723166;4482.551744825491;4513.932291015637;1153.646561015519;1155.8324043013715;1158.449736375215;1178.0810967445407;1204.6145233525363;20810;20975.895120524605;21022.579548409252;21075.68386474204;21207.045682944303;1164.9009834164021;1165.930044197351;1181.4329122021306;1187.8633957094871;1217.0689422591724;11190.608328708457;11413.942930285295;11435.199536032398;11441.71395715097;11541.019013383788;271356;271493;271552;271862;273065;99272.50363748135;105825.47087264668;112996;127624;129040;1397.3083323754806;1460.2625674034546;1461.043016823982;1467.801846750675;1474.0492382413283;13191.756511594614;18097.457076287734;19664.213431463155;19689.80310196937;19760.209591616174;50869;51993.22010169926;52465.47534524606;53251.733741331045;54399.45600543944;64192.67903660452;64703.352966469734;64934.35065649283;65251;66365.33634663592;12637.368131593199;12705.602383737596;12747.61757147287;12754.872451275369;12868.613941581767;14014.229217393142;14124.929375353058;14145.292735363397;14236.572902812932;14292.142471451747;12188.878111218773;12288.201266917622;12514;12539.87460125387;12925.676858078623;11314.773704526027;11488.19582629208;11537.538498459968;11542.307461552333;11589.130815188726;12407.51734907185;12497.750044999231;12559.183653062522;12724.109312348046;12917;11281.32312061279;12020.33888136161;12596.685082872635;12692.619221423372;12766.042546809213;12432.627021189379;12460.937695311828;12485.31330776816;12490.812637810259;12535.373231338213 02:00:33 INFO - PROCESS | 19952 | |3;mootools.html;1570.5680937742113;1592.7531232658935;1607.2124658917135;1611.613212828921;1622.4402581109514;10832.350058996455;11607.419629018557;11608.361540115291;11613.245139065966;11626.825597616042;11012.339259644426;11357.716057098576;11377.089832813375;11392.886071139286;11395.088392928566;2474.381404648838;2508.648789169518;2510.1089113364765;2511.09600543805;2516.471540976397;2419.0928401849305;2463.3841539615096;2496.0889275414434;2503.5118151960373;2505;2288;2294.8623082615013;2387.904483820645;2437.061731233479;2441.292025312657;2291.495870908414;2313.606686863229;2314.722233331994;2322.3149170994598;2391.0196819304174;2288.3706980580305;2304.8040916522114;2306.0660432524687;2314.90740370385;2319.420144963759;11413.144014198968;11740.471678774476;11767.293962362286;11768.588099416565;11779.352135632536;5773.220615216976;5862.14998825168;5894.469497745181;5905.586608937376;5915.940840591625;3529.3294274087843;3575.5173051637958;3606.296772129436;3631.2918980798754;3632.4914511968345;1424.5726282115427;1426.0873041253606;1484.4507532213042;2314.155333303349;2320.164740693349;14886.851131488655;15230.923845380703;16707.33170673184;17878.106094695344;17935.641287174185;4942.258661200849;5054.519820617035;5147.382314122255;5234.712090834964;5264.10510213257;14679.55961321162;14700.485483008044;14734.852651473348;14772.48296309624;14788.260586970608;6297.433231009231;6315;6325.272593651789;6337.271213810379;6365.840853978595;5671.404502527258;5714.542836573064;5760.481556659837;5765.308163020464;5773.278340207474;4368.60682538573;4381.38660587518;4470.374147619336;4506.121306345297;4511.210538155809;1104.9723756906142;1110.272771334777;1147.17976646698;1147.9655610331704;1148.9942550287194;1018.7860549284733;1030.175859312547;1043.0664555223034;1045.210865796319;1046.874375074996;6961.512694111364;7104.751333703295;7116.146062472433;7237.203907570163;7251.238619944936;1037.2894567221476;1079.8164312066967;1080.8162612355918;1099;1128.9548418063187;4477.1493416250805;4839.806407743651;4914.33656456381;4953.801847926043;5042.949570504321;13936.233507157203;13943.930280348533;14007.509737159151;14473.348699308557;14473.565793026226;13891;13960.720785584435;14040.789388159186;14045;14048.929755351362;9006.86489702668;10494.895051049392;10633.893661063445;10641.095506882002;10658.253922225369;6956.2000369958105;7564;7698.538087714755;7718.845623087394;7842.882356764767;12543.749125017632;12557.623271301876;12632.43154058088;12848.935755321163;12927.741445171232;12720.618381448572;12794.016927376271;12804;12864.485420583072;12961.41673624708;10178.23663225276;10190.337628054152;10270.897291026995;10274.33216840903;10281.228907832092;11078.612248571262;11106.16703747206;11157.497912594115;11170.888291116984;11219.158563107963;9413.576389062371;9460.10129037768;9512.762180945256;9541.093596108358;9681.080297371738;9178.541072946193;9363.063693630855;9377.1091746284;9520.952395237979;9585.376950498196;8383.748487545383;8793.428427152214;8924.375293729636;8928.375013749235;8930.062343454103;6330.303666596763;6428.164338636135;6478.935210647834;6494.935050649433;6588.7364505421165;6194.4115309045565;6340.4293613575;6347.3652634738;6422.646754428364;6487.805365839033 02:00:33 INFO - PROCESS | 19952 | |4;prototype.html;422.7717032802287;436.13209712671784;447.0165635601677;451.32076225280946;454.677179202766;5213.1919552469335;5224.68651880887;5245.108331583635;5251.973740131303;5269.735263536752;5101.7194054327;5190.273361729361;5208.322918020662;5208.374995000596;5225.137852254374;4144.295469770132;4166;4172.666186705056;4185.979070104645;4192.4759405074365;4151.481064866892;4152.4186613873935;4156.438880751105;4160.687948403866;4182.79086045698;4177.582241775828;4178.895527611804;4182.372644103379;4213.157368526307;4233.407322974786;4043.555208927016;4089.345704687236;4155;4184.665226781881;4205.705600607944;3765.4351847223134;3777.4333849922455;3780.8468417132763;3803.6576708096127;3807.7624771949227;5044.646874718771;5049.798008079672;5051.949480505222;5068.4424713281505;5084.872878178038;4678.228092364756;4737.478877323491;4807.735574543399;4828.758562071883;4906.779194936236;5496.5983674162835;5529.612927095105;5547.611667183299;5547.639403438804;5554.805581804618;4292.79801655537;4310.159518893817;4313.309870420718;4313.633341166004;4319.552949761834;5474.507294343528;5556.388797232301;5600.719964001824;5680.8187415047705;5728.570357223225;4358.063016451478;4361.3676016978;4378.60592546666;4401.273789824643;4412.33814927757;4462;4469.284914413678;4472.300525800175;4498.032922921522;4506.895810526376;4720.173969555314;4844.878878028077;4884.876478409915;4903.215485522371;4929.556339929423;5522.475364840334;5523.613347065667;5532.2531458253425;5534.501894829405;5542.778288868481;1705.9573510662085;1706.385701147586;1724.6205834716343;1731.3161301286173;1735.670222657691;1391.2278685329738;1422.8719415252676;1446.616646588655;1453.4694836384645;1460.4450308882556;5365.275687782099;5389.51494365509;5402.945970540323;5419.268398766116;5421.966762464076;5351.81268655603;5620.241267428844;5631.71841407928;5635.3519345275;5708.543316534668;5406.972965135228;5411.188321751769;5415.972920135374;5425.674459532441;5440.483154100354;2941.749951254167;2945.6450032985067;2968.985155074211;2976.4642364374185;2977.493826049577;5602.355729091125;5762.279715035621;5825.446582574648;5849.561282903799;5892.467958330779;5526.397344769972;5642.379338272786;5768.596198266081;5770.913436298459;5779.393163717833;5789.88420231593;5832.562557808096;5839.795607153729;5840.2115714378015;5852.326982397079;5608.355039170385;5622.690752008679;5639.210510528611;5641.348566173891;5652.88694226105;5243.606729495227;5249.818790772046;5533.446655334435;5576;5592.937341905025;5801.3038435388025;5849.61977471463;5852.619579727304;5852.970735146297;5867.559933005127;4537.1833070047705;4649.790759415902;4670.743109129031;4672.649551283599;4692.319613656069;5591.4688104629995;5614.933162699074;5625.774969001194;5642.520385767256;5661.518770904519;5478.863028424321;5494.890102197854;5513.8420931603205;5516.448355164452;5554.472325129106;5408.59435542328;5420.620556561161;5432.891342173055;5454.699991500505;5557.41647127062;5423.701696406735;5432.809851655173;5439.700816454974;5482.835514934558;5506.284183056177;5379.273798037215;5507.118860982224;5512.91730624049;5534.501894829485;5535.34667119699;5395.919061214164;5419.3225846769155;5425.1554471479885;5444.8094316698725;5454.4545545445135 02:00:33 INFO - PROCESS | 19952 | |5;yui.html;861.6510313323098;869.2263885142221;908.7728067983004;920.9171174594285;921.5898924978383;17374.652506949868;18104.637907241864;18490.537736556576;18810.15354309056;18975.810241897576;17051.91474042628;17248;17255.396061137864;17753;17928.551786205353;870.7997160653053;872.4721543466222;888.62233550741;890.5235698901089;891.0109778146252;876.2376732242926;879.6261588824756;887.8135591525787;888.7911340834899;889.2397000564521;911.08436021798;916.8533034714447;920.7145784806731;922.0871337376017;922.1562270522464;908.0783005249676;908.4867050116663;917.8072604752944;918.6968300460832;919.2599957034585;674.9257581666013;678.5148618737626;679.5413096160072;679.8402375441767;680.5576375356009;16432.589185270343;16620.418285360076;16919.154042297836;18590.90704546482;18652.34716784906;15166;16122;16177.83822161775;16270.593235169097;16522.917385413115;13467.393967271497;13785.035047546677;13810.65473363164;13861;14072.859271407257;4041.211963667086;4061.4720086388875;4088.693347998882;4109.91780164398;4140.130572579732;69036;69039.65480172567;91357.54321228417;92563.07436925679;93222.5338873308;12091.274523528617;12285.262884226975;12338.568150114703;12367.814482782765;12481.500739970299;21712.674309885366;22351.329460116223;22368.776312236994;22413.215537456435;23136.884315578314;9157.038510956387;9252.629894804268;9264.44413335202;9271.860922086174;9312.906870931203;4665.113628410659;4678.8128474861305;4705.811767529329;4730.929036064462;4770.904581908342;3978.347095068215;4001.47980762499;4035.031592417857;4089.754614723126;4094.549599544048;675.9222689390783;682.4574463301688;695.5861262548876;724.2865777209445;763.9006929099181;864.6800683747053;873.9825203496022;880.1418616848649;880.7357792662176;891.5319457284977;11363.14776391774;11501.482433290523;11635;11827.349495777815;11951.820722689166;865.6018231613402;869.5087275689342;871.4205053639386;878.6221924572368;889.9777505562413;5223;5425.6026517669925;5503.834884953458;5567.582431317667;5579.330480342385;64427.67786161039;65755;66737;79471.20528794754;83828.58085709532;59736.402635973085;68381.65809171021;68866;69294.65352673305;70118.64940675366;61744;61757.38242617606;61859;62325.68837155785;63481.68259158767;10752.677419677422;10917.617883374362;11078.612248571262;11254.718632033937;11290;40950.79524602477;41521.37717934294;41727.791361043;41836;43032;48702.75648621734;49614.00771984612;49723.254151188485;49909.50090499194;50078;13764.105333153313;13796.517121900686;13804.792928105886;13985.720285594434;14164.150150990974;16413.67172656564;16663.66672666564;16796.748048779522;16843.831561684718;17049.829501704826;15132.546023619308;15306.77039844381;15638.530844074696;16368;16400.75398869042;15422.614434639223;15550.455734049254;15565.844341556893;16216.351345946501;16799.496015119566;14175.43298268099;14486.565403037926;14709.41162353494;15190;15205.923970380078;10548.578056877639;10650.786984260116;11035.39305338182;11138.888611113785;11392.316461012366;12447.253164810141;12522;12831.615051548468;12849.614511564294;12910.22538647684 02:00:33 INFO - PROCESS | 19952 | __end_tp_report 02:00:33 INFO - PROCESS | 19952 | __start_cc_report 02:00:33 INFO - PROCESS | 19952 | _x_x_mozilla_cycle_collect,1425 02:00:33 INFO - PROCESS | 19952 | __end_cc_report 02:00:33 INFO - PROCESS | 19952 | __startTimestamp1493110833773__endTimestamp 02:00:33 INFO - PROCESS | 19952 | 02:00:33 INFO - PROCESS | 19952 | ------- Summary: start ------- 02:00:33 INFO - PROCESS | 19952 | Number of tests: 6 02:00:33 INFO - PROCESS | 19952 | 02:00:33 INFO - PROCESS | 19952 | [#0] dojo.html Cycles:165 Average:5787.77 Median:6042.97 stddev:680.12 (11.3%) stddev-sans-first:656.46 02:00:33 INFO - PROCESS | 19952 | Values: 3425.0 3430.3 3446.7 3450.6 3486.9 5739.7 5753.1 5779.6 5782.9 5805.4 5823.3 5868.9 5876.5 5883.3 5886.8 5192.1 5229.0 5241.2 5243.7 5288.5 5057.8 5094.1 5281.9 5285.6 5289.8 5161.1 5162.2 5245.5 5252.8 5273.8 5263.9 5276.3 5279.7 5279.7 5288.5 5005.5 5100.2 5175.2 5229.5 5236.0 5674.9 5724.3 5740.7 5795.4 5799.2 6225.4 6308.8 6309.6 6316.9 6323.5 6323.8 6326.8 6335.9 6339.5 6339.7 5650.9 5692.7 5816.0 5839.6 5935.5 6424.9 6425.8 6432.2 6437.7 6439.7 6144.5 6147.8 6214.1 6220.2 6220.8 6041.8 6074.9 6155.1 6304.9 6316.2 6213.0 6257.0 6274.3 6274.5 6284.7 6018.0 6044.0 6046.5 6092.5 6270.2 4642.6 4729.8 4747.9 4755.1 4762.9 4136.9 4145.6 4176.6 4214.1 4231.8 5938.3 5976.4 5991.8 6017.8 6024.4 6237.3 6251.1 6261.4 6267.0 6274.8 5692.4 5729.7 5994.5 6010.8 6013.6 5205.3 5309.7 5334.0 5360.8 5380.4 6564.2 6592.5 6595.8 6606.4 6611.5 6065.9 6270.5 6329.7 6337.8 6337.8 6387.4 6402.3 6415.0 6488.8 6514.5 6090.6 6093.0 6103.7 6105.0 6120.9 6202.9 6228.5 6292.1 6293.9 6303.0 5849.0 5881.1 6028.6 6055.6 6132.9 6113.4 6155.5 6166.7 6171.3 6198.6 5920.5 6128.4 6171.7 6173.1 6176.2 6041.7 6111.9 6119.7 6142.7 6218.0 6043.0 6074.2 6077.4 6081.1 6113.3 02:00:33 INFO - PROCESS | 19952 | 02:00:33 INFO - PROCESS | 19952 | [#1] ext.html Cycles:180 Average:42324.08 Median:29711.62 stddev:66237.58 (222.9%) stddev-sans-first:66349.83 02:00:33 INFO - PROCESS | 19952 | Values: 748.7 826.5 842.9 894.5 906.1 30767.7 31161.8 33598.0 43250.8 44547.6 44369.0 44512.8 44532.8 44583.3 44731.6 9060.8 9168.3 9187.6 9188.4 9190.4 7529.4 7758.1 10621.8 10838.7 10855.9 10859.6 10885.3 10897.9 10914.9 10918.0 10811.2 10859.6 10883.0 10884.4 10904.6 10690.7 10767.8 10777.2 10778.9 10778.9 41954.4 42026.0 42131.0 42183.2 42274.2 4219.0 4252.2 4259.4 4337.1 4406.6 29674.9 30075.0 30125.7 30822.8 31366.2 3851.9 3923.9 3926.2 3932.2 4025.6 218177.0 218255.0 218285.0 218861.0 218902.0 53223.7 53329.0 53367.5 53668.0 53729.7 53126.5 53283.5 53295.2 53308.2 53341.5 16458.9 16810.1 16818.1 16821.8 16822.3 11946.4 14915.4 14946.3 14952.1 14961.7 12235.5 13209.9 13393.8 13548.3 13984.9 5460.4 5504.9 5543.9 5555.4 5597.7 1030.9 1038.5 1039.5 1053.0 1061.4 27966.0 28393.4 28643.4 28674.0 28893.3 1112.0 1112.8 1125.6 1139.8 1150.9 14962.8 15084.7 15150.8 15215.8 15380.2 271584.0 275769.0 276530.0 276875.0 276912.0 55889.7 55958.4 56115.2 56182.4 56238.7 1501.3 1555.4 1562.8 1580.9 1605.5 25557.9 27365.5 27480.6 27590.0 27727.6 43307.6 43316.4 43353.3 43461.6 43486.2 262991.0 264806.0 268220.0 268549.0 268672.0 29977.3 31305.2 35696.8 35766.5 35884.0 38398.0 38417.8 38705.2 38766.8 38799.4 29568.0 29691.0 29764.9 29807.6 29887.7 29732.3 29960.7 30064.4 30397.0 30633.4 33801.2 33861.8 33935.0 34111.7 34157.7 28559.7 28577.0 28718.1 28734.4 29139.9 22466.7 23427.3 23506.3 23619.9 23827.3 02:00:33 INFO - PROCESS | 19952 | 02:00:33 INFO - PROCESS | 19952 | [#2] jquery.html Cycles:180 Average:27761.96 Median:20999.24 stddev:49753.41 (236.9%) stddev-sans-first:49850.62 02:00:33 INFO - PROCESS | 19952 | Values: 415.6 443.7 459.2 464.4 476.7 19480.1 19701.6 20071.5 21451.9 21690.8 21197.6 21360.9 21592.0 21617.4 21685.7 6575.4 6656.9 6723.6 6776.6 6816.8 4116.3 6597.5 6719.4 6734.9 6745.2 4381.2 4404.7 4789.0 7714.2 7921.4 7631.1 7657.0 7717.7 7732.1 7822.6 7692.8 7704.5 7705.2 7707.8 7709.9 26225.0 26860.7 27047.0 27563.7 27775.6 3641.8 3644.6 3696.5 3757.2 3787.9 15656.2 17149.7 17429.4 17899.6 18383.0 2275.6 2330.5 2380.8 2398.6 2400.7 119876.4 122881.0 123250.4 123445.0 124431.4 15718.8 20456.3 21481.5 22192.3 22845.5 44351.0 44483.8 44631.6 45105.3 45354.5 11584.0 11736.8 11820.2 11828.5 11960.3 9548.3 9647.5 9729.5 9848.0 9863.5 11165.0 11362.3 11514.8 11582.5 11680.0 3924.7 4054.7 4254.4 4482.6 4513.9 1153.6 1155.8 1158.4 1178.1 1204.6 20810.0 20975.9 21022.6 21075.7 21207.0 1164.9 1165.9 1181.4 1187.9 1217.1 11190.6 11413.9 11435.2 11441.7 11541.0 271356.0 271493.0 271552.0 271862.0 273065.0 99272.5 105825.5 112996.0 127624.0 129040.0 1397.3 1460.3 1461.0 1467.8 1474.0 13191.8 18097.5 19664.2 19689.8 19760.2 50869.0 51993.2 52465.5 53251.7 54399.5 64192.7 64703.4 64934.4 65251.0 66365.3 12637.4 12705.6 12747.6 12754.9 12868.6 14014.2 14124.9 14145.3 14236.6 14292.1 12188.9 12288.2 12514.0 12539.9 12925.7 11314.8 11488.2 11537.5 11542.3 11589.1 12407.5 12497.8 12559.2 12724.1 12917.0 11281.3 12020.3 12596.7 12692.6 12766.0 12432.6 12460.9 12485.3 12490.8 12535.4 02:00:33 INFO - PROCESS | 19952 | 02:00:33 INFO - PROCESS | 19952 | [#3] mootools.html Cycles:180 Average:7247.40 Median:2314.81 stddev:4515.89 (195.1%) stddev-sans-first:4508.41 02:00:33 INFO - PROCESS | 19952 | Values: 1570.6 1592.8 1607.2 1611.6 1622.4 10832.4 11607.4 11608.4 11613.2 11626.8 11012.3 11357.7 11377.1 11392.9 11395.1 2474.4 2508.6 2510.1 2511.1 2516.5 2419.1 2463.4 2496.1 2503.5 2505.0 2288.0 2294.9 2387.9 2437.1 2441.3 2291.5 2313.6 2314.7 2322.3 2391.0 2288.4 2304.8 2306.1 2314.9 2319.4 11413.1 11740.5 11767.3 11768.6 11779.4 5773.2 5862.1 5894.5 5905.6 5915.9 3529.3 3575.5 3606.3 3631.3 3632.5 1424.6 1426.1 1484.5 2314.2 2320.2 14886.9 15230.9 16707.3 17878.1 17935.6 4942.3 5054.5 5147.4 5234.7 5264.1 14679.6 14700.5 14734.9 14772.5 14788.3 6297.4 6315.0 6325.3 6337.3 6365.8 5671.4 5714.5 5760.5 5765.3 5773.3 4368.6 4381.4 4470.4 4506.1 4511.2 1105.0 1110.3 1147.2 1148.0 1149.0 1018.8 1030.2 1043.1 1045.2 1046.9 6961.5 7104.8 7116.1 7237.2 7251.2 1037.3 1079.8 1080.8 1099.0 1129.0 4477.1 4839.8 4914.3 4953.8 5042.9 13936.2 13943.9 14007.5 14473.3 14473.6 13891.0 13960.7 14040.8 14045.0 14048.9 9006.9 10494.9 10633.9 10641.1 10658.3 6956.2 7564.0 7698.5 7718.8 7842.9 12543.7 12557.6 12632.4 12848.9 12927.7 12720.6 12794.0 12804.0 12864.5 12961.4 10178.2 10190.3 10270.9 10274.3 10281.2 11078.6 11106.2 11157.5 11170.9 11219.2 9413.6 9460.1 9512.8 9541.1 9681.1 9178.5 9363.1 9377.1 9521.0 9585.4 8383.7 8793.4 8924.4 8928.4 8930.1 6330.3 6428.2 6478.9 6494.9 6588.7 6194.4 6340.4 6347.4 6422.6 6487.8 02:00:33 INFO - PROCESS | 19952 | 02:00:33 INFO - PROCESS | 19952 | [#4] prototype.html Cycles:180 Average:4729.33 Median:5260.85 stddev:1270.01 (24.1%) stddev-sans-first:1231.75 02:00:33 INFO - PROCESS | 19952 | Values: 422.8 436.1 447.0 451.3 454.7 5213.2 5224.7 5245.1 5252.0 5269.7 5101.7 5190.3 5208.3 5208.4 5225.1 4144.3 4166.0 4172.7 4186.0 4192.5 4151.5 4152.4 4156.4 4160.7 4182.8 4177.6 4178.9 4182.4 4213.2 4233.4 4043.6 4089.3 4155.0 4184.7 4205.7 3765.4 3777.4 3780.8 3803.7 3807.8 5044.6 5049.8 5051.9 5068.4 5084.9 4678.2 4737.5 4807.7 4828.8 4906.8 5496.6 5529.6 5547.6 5547.6 5554.8 4292.8 4310.2 4313.3 4313.6 4319.6 5474.5 5556.4 5600.7 5680.8 5728.6 4358.1 4361.4 4378.6 4401.3 4412.3 4462.0 4469.3 4472.3 4498.0 4506.9 4720.2 4844.9 4884.9 4903.2 4929.6 5522.5 5523.6 5532.3 5534.5 5542.8 1706.0 1706.4 1724.6 1731.3 1735.7 1391.2 1422.9 1446.6 1453.5 1460.4 5365.3 5389.5 5402.9 5419.3 5422.0 5351.8 5620.2 5631.7 5635.4 5708.5 5407.0 5411.2 5416.0 5425.7 5440.5 2941.7 2945.6 2969.0 2976.5 2977.5 5602.4 5762.3 5825.4 5849.6 5892.5 5526.4 5642.4 5768.6 5770.9 5779.4 5789.9 5832.6 5839.8 5840.2 5852.3 5608.4 5622.7 5639.2 5641.3 5652.9 5243.6 5249.8 5533.4 5576.0 5592.9 5801.3 5849.6 5852.6 5853.0 5867.6 4537.2 4649.8 4670.7 4672.6 4692.3 5591.5 5614.9 5625.8 5642.5 5661.5 5478.9 5494.9 5513.8 5516.4 5554.5 5408.6 5420.6 5432.9 5454.7 5557.4 5423.7 5432.8 5439.7 5482.8 5506.3 5379.3 5507.1 5512.9 5534.5 5535.3 5395.9 5419.3 5425.2 5444.8 5454.5 02:00:33 INFO - PROCESS | 19952 | 02:00:33 INFO - PROCESS | 19952 | [#5] yui.html Cycles:180 Average:18124.42 Median:22830.75 stddev:21817.02 (95.6%) stddev-sans-first:21839.71 02:00:33 INFO - PROCESS | 19952 | Values: 861.7 869.2 908.8 920.9 921.6 17374.7 18104.6 18490.5 18810.2 18975.8 17051.9 17248.0 17255.4 17753.0 17928.6 870.8 872.5 888.6 890.5 891.0 876.2 879.6 887.8 888.8 889.2 911.1 916.9 920.7 922.1 922.2 908.1 908.5 917.8 918.7 919.3 674.9 678.5 679.5 679.8 680.6 16432.6 16620.4 16919.2 18590.9 18652.3 15166.0 16122.0 16177.8 16270.6 16522.9 13467.4 13785.0 13810.7 13861.0 14072.9 4041.2 4061.5 4088.7 4109.9 4140.1 69036.0 69039.7 91357.5 92563.1 93222.5 12091.3 12285.3 12338.6 12367.8 12481.5 21712.7 22351.3 22368.8 22413.2 23136.9 9157.0 9252.6 9264.4 9271.9 9312.9 4665.1 4678.8 4705.8 4730.9 4770.9 3978.3 4001.5 4035.0 4089.8 4094.5 675.9 682.5 695.6 724.3 763.9 864.7 874.0 880.1 880.7 891.5 11363.1 11501.5 11635.0 11827.3 11951.8 865.6 869.5 871.4 878.6 890.0 5223.0 5425.6 5503.8 5567.6 5579.3 64427.7 65755.0 66737.0 79471.2 83828.6 59736.4 68381.7 68866.0 69294.7 70118.6 61744.0 61757.4 61859.0 62325.7 63481.7 10752.7 10917.6 11078.6 11254.7 11290.0 40950.8 41521.4 41727.8 41836.0 43032.0 48702.8 49614.0 49723.3 49909.5 50078.0 13764.1 13796.5 13804.8 13985.7 14164.2 16413.7 16663.7 16796.7 16843.8 17049.8 15132.5 15306.8 15638.5 16368.0 16400.8 15422.6 15550.5 15565.8 16216.4 16799.5 14175.4 14486.6 14709.4 15190.0 15205.9 10548.6 10650.8 11035.4 11138.9 11392.3 12447.3 12522.0 12831.6 12849.6 12910.2 02:00:33 INFO - PROCESS | 19952 | -------- Summary: end -------- 02:00:33 INFO - PROCESS | 19952 | 02:00:34 INFO - TEST-INFO | 19952: exit 0 02:00:34 INFO - TEST-OK | dromaeo_css | took 1115163ms 02:00:34 INFO - TEST-START | kraken 02:00:34 INFO - operating with platform_type : linux_ 02:00:34 INFO - Initialising browser for kraken test... 02:00:34 INFO - TEST-INFO | started process 20276 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWyFCsx/profile http://localhost:41649/getInfo.html) 02:00:38 INFO - PROCESS | 20276 | __metrics Screen width/height:1600/1200 02:00:38 INFO - PROCESS | 20276 | colorDepth:24 02:00:38 INFO - PROCESS | 20276 | Browser inner width/height: 1024/697 02:00:38 INFO - PROCESS | 20276 | __metrics 02:00:39 INFO - PROCESS | 20276 | 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:00:39 INFO - PROCESS | 20276 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:00:40 INFO - TEST-INFO | 20276: exit 0 02:00:40 INFO - Browser initialized. 02:00:40 INFO - Running cycle 1/1 for kraken test... 02:00:40 INFO - TEST-INFO | started process 20369 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWyFCsx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:00:51 INFO - PROCESS | 20369 | RSS: Main: 140079104 02:00:51 INFO - PROCESS | 20369 | 02:00:54 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41649/tests/kraken/driver.html?testName=audio-beat-detection) 02:00:55 INFO - PROCESS | 20369 | RSS: Main: 183296000 02:00:55 INFO - PROCESS | 20369 | 02:00:57 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41649/tests/kraken/driver.html?testName=audio-dft) 02:00:58 INFO - PROCESS | 20369 | RSS: Main: 174125056 02:00:58 INFO - PROCESS | 20369 | 02:01:01 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41649/tests/kraken/driver.html?testName=audio-fft) 02:01:01 INFO - PROCESS | 20369 | RSS: Main: 266158080 02:01:01 INFO - PROCESS | 20369 | 02:01:03 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41649/tests/kraken/driver.html?testName=audio-oscillator) 02:01:04 INFO - PROCESS | 20369 | RSS: Main: 172531712 02:01:04 INFO - PROCESS | 20369 | 02:01:06 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41649/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:01:07 INFO - PROCESS | 20369 | RSS: Main: 175054848 02:01:07 INFO - PROCESS | 20369 | 02:01:10 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41649/tests/kraken/driver.html?testName=imaging-darkroom) 02:01:10 INFO - PROCESS | 20369 | RSS: Main: 213942272 02:01:10 INFO - PROCESS | 20369 | 02:01:14 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41649/tests/kraken/driver.html?testName=imaging-desaturate) 02:01:14 INFO - PROCESS | 20369 | RSS: Main: 212283392 02:01:14 INFO - PROCESS | 20369 | 02:01:17 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41649/tests/kraken/driver.html?testName=json-parse-financial) 02:01:18 INFO - PROCESS | 20369 | RSS: Main: 210690048 02:01:18 INFO - PROCESS | 20369 | 02:01:19 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41649/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:01:20 INFO - PROCESS | 20369 | RSS: Main: 173047808 02:01:20 INFO - PROCESS | 20369 | 02:01:21 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41649/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:01:22 INFO - PROCESS | 20369 | RSS: Main: 179073024 02:01:22 INFO - PROCESS | 20369 | 02:01:24 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41649/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:01:25 INFO - PROCESS | 20369 | RSS: Main: 188948480 02:01:25 INFO - PROCESS | 20369 | 02:01:27 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41649/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:01:28 INFO - PROCESS | 20369 | RSS: Main: 199049216 02:01:28 INFO - PROCESS | 20369 | 02:01:31 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41649/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:01:31 INFO - PROCESS | 20369 | RSS: Main: 194916352 02:01:31 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | Cycle 1(1): loaded http://localhost:41649/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41649/tests/kraken/driver.html?testName=ai-astar) 02:01:33 INFO - PROCESS | 20369 | RSS: Main: 246669312 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | __start_tp_report 02:01:33 INFO - PROCESS | 20369 | _x_x_mozilla_page_load 02:01:33 INFO - PROCESS | 20369 | _x_x_mozilla_page_load_details 02:01:33 INFO - PROCESS | 20369 | |i|pagename|runs| 02:01:33 INFO - PROCESS | 20369 | |0;ai-astar;103;112;112;111;112;111;112;111;111;112 02:01:33 INFO - PROCESS | 20369 | |1;audio-beat-detection;148;116;117;155;116;116;114;115;136;116 02:01:33 INFO - PROCESS | 20369 | |2;audio-dft;161;160;161;161;161;161;163;162;161;160 02:01:33 INFO - PROCESS | 20369 | |3;audio-fft;74;75;75;108;75;76;76;75;89;74 02:01:33 INFO - PROCESS | 20369 | |4;audio-oscillator;82;82;102;82;83;81;98;83;82;102 02:01:33 INFO - PROCESS | 20369 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;113;113;113 02:01:33 INFO - PROCESS | 20369 | |6;imaging-darkroom;147;147;146;147;147;146;146;149;149;147 02:01:33 INFO - PROCESS | 20369 | |7;imaging-desaturate;94;94;94;94;94;93;94;94;93;93 02:01:33 INFO - PROCESS | 20369 | |8;json-parse-financial;59;54;56;55;56;57;55;54;55;55 02:01:33 INFO - PROCESS | 20369 | |9;json-stringify-tinderbox;54;55;54;54;54;54;55;54;54;54 02:01:33 INFO - PROCESS | 20369 | |10;stanford-crypto-aes;81;76;80;74;79;80;76;76;75;76 02:01:33 INFO - PROCESS | 20369 | |11;stanford-crypto-ccm;124;120;121;122;122;123;118;120;161;146 02:01:33 INFO - PROCESS | 20369 | |12;stanford-crypto-pbkdf2;170;164;166;158;164;166;164;165;166;167 02:01:33 INFO - PROCESS | 20369 | |13;stanford-crypto-sha256-iterative;64;66;61;60;61;59;60;63;59;60 02:01:33 INFO - PROCESS | 20369 | __end_tp_report 02:01:33 INFO - PROCESS | 20369 | __start_cc_report 02:01:33 INFO - PROCESS | 20369 | _x_x_mozilla_cycle_collect,1637 02:01:33 INFO - PROCESS | 20369 | __end_cc_report 02:01:33 INFO - PROCESS | 20369 | __startTimestamp1493110893490__endTimestamp 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | ------- Summary: start ------- 02:01:33 INFO - PROCESS | 20369 | Number of tests: 14 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:2.75 (2.5%) stddev-sans-first:0.53 02:01:33 INFO - PROCESS | 20369 | Values: 103.0 112.0 112.0 111.0 112.0 111.0 112.0 111.0 111.0 112.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#1] audio-beat-detection Cycles:10 Average:124.90 Median:116.50 stddev:15.49 (13.3%) stddev-sans-first:13.99 02:01:33 INFO - PROCESS | 20369 | Values: 148.0 116.0 117.0 155.0 116.0 116.0 114.0 115.0 136.0 116.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 02:01:33 INFO - PROCESS | 20369 | Values: 161.0 160.0 161.0 161.0 161.0 161.0 163.0 162.0 161.0 160.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#3] audio-fft Cycles:10 Average:79.70 Median:75.00 stddev:10.89 (14.5%) stddev-sans-first:11.36 02:01:33 INFO - PROCESS | 20369 | Values: 74.0 75.0 75.0 108.0 75.0 76.0 76.0 75.0 89.0 74.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:82.00 stddev:9.03 (11.0%) stddev-sans-first:9.34 02:01:33 INFO - PROCESS | 20369 | Values: 82.0 82.0 102.0 82.0 83.0 81.0 98.0 83.0 82.0 102.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 02:01:33 INFO - PROCESS | 20369 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 02:01:33 INFO - PROCESS | 20369 | Values: 147.0 147.0 146.0 147.0 147.0 146.0 146.0 149.0 149.0 147.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 02:01:33 INFO - PROCESS | 20369 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 93.0 93.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.50 stddev:1.51 (2.7%) stddev-sans-first:0.97 02:01:33 INFO - PROCESS | 20369 | Values: 59.0 54.0 56.0 55.0 56.0 57.0 55.0 54.0 55.0 55.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#9] json-stringify-tinderbox Cycles:10 Average:54.20 Median:54.00 stddev:0.42 (0.8%) stddev-sans-first:0.44 02:01:33 INFO - PROCESS | 20369 | Values: 54.0 55.0 54.0 54.0 54.0 54.0 55.0 54.0 54.0 54.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#10] stanford-crypto-aes Cycles:10 Average:77.30 Median:77.50 stddev:2.45 (3.2%) stddev-sans-first:2.20 02:01:33 INFO - PROCESS | 20369 | Values: 81.0 76.0 80.0 74.0 79.0 80.0 76.0 76.0 75.0 76.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:122.50 stddev:14.15 (11.6%) stddev-sans-first:14.95 02:01:33 INFO - PROCESS | 20369 | Values: 124.0 120.0 121.0 122.0 122.0 123.0 118.0 120.0 161.0 146.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:166.00 stddev:3.06 (1.8%) stddev-sans-first:2.65 02:01:33 INFO - PROCESS | 20369 | Values: 170.0 164.0 166.0 158.0 164.0 166.0 164.0 165.0 166.0 167.0 02:01:33 INFO - PROCESS | 20369 | 02:01:33 INFO - PROCESS | 20369 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.00 stddev:2.31 (3.8%) stddev-sans-first:2.24 02:01:33 INFO - PROCESS | 20369 | Values: 64.0 66.0 61.0 60.0 61.0 59.0 60.0 63.0 59.0 60.0 02:01:33 INFO - PROCESS | 20369 | -------- Summary: end -------- 02:01:33 INFO - PROCESS | 20369 | 02:01:34 INFO - TEST-INFO | 20369: exit 0 02:01:34 INFO - TEST-OK | kraken | took 59977ms 02:01:34 INFO - SUITE-END | took 1175s 02:01:34 INFO - Completed test suite (00:19:35) 02:01:34 INFO - javascript benchmark 02:01:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7882.81779043041, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3424.9657503424964, 3430.3482338355725, 3446.707029902457, 3450.6204317525053, 3486.930261394772, 5739.741711622976, 5753.079507278836, 5779.62432441891, 5782.942170578293, 5805.419458054193, 5823.3012038555435, 5868.882622347561, 5876.5004974577105, 5883.323417806942, 5886.764529418829, 5192.065428222918, 5228.973855130719, 5241.213817927322, 5243.659162154467, 5288.497592728685, 5057.822976195834, 5094.133997220481, 5281.8943621127555, 5285.6300058995885, 5289.761960711778, 5161.148410512261, 5162.199859021839, 5245.4754524547625, 5252.8161514347, 5273.841784746463, 5263.86840328991, 5276.261323414725, 5279.709615971139, 5279.736013199363, 5288.497592728685, 5005.47442518534, 5100.183970564729, 5175.249588809639, 5229.47705229474, 5236.0, 5674.858128546819, 5724.341700704389, 5740.655560666373, 5795.449432303924, 5799.246098007275, 6225.377462253784, 6308.779192728232, 6309.621422714606, 6316.905246421307, 6323.494120470351, 6323.7786677466065, 6326.841828954313, 6335.873282534323, 6339.492840572743, 6339.683015849189, 5650.943490565083, 5692.7153642318135, 5816.011278082694, 5839.649621022752, 5935.495482884004, 6424.903626445607, 6425.807225783234, 6432.195975503062, 6437.67811609427, 6439.678016099177, 6144.5084393248435, 6147.815565533041, 6214.067889816473, 6220.160278362363, 6220.844478888064, 6041.788537401258, 6074.878502429927, 6155.076738489262, 6304.905426418607, 6316.178896743486, 6213.0, 6256.968715156486, 6274.2784579773, 6274.466670332981, 6284.717187726564, 6017.969910150508, 6044.032954727222, 6046.455818976313, 6092.51259899207, 6270.153529273581, 4642.558956899089, 4729.834455794031, 4747.881302967454, 4755.120302744003, 4762.928556071662, 4136.855210067633, 4145.606167414091, 4176.644985176294, 4214.072903961123, 4231.767252801125, 5938.346781853993, 5976.372480889531, 5991.820245392558, 6017.789377371859, 6024.367441418588, 6237.313895471494, 6251.093591429218, 6261.373862613702, 6267.0, 6274.843128921722, 5692.37383887772, 5729.656220626693, 5994.460498555064, 6010.819675409744, 6013.609115407485, 5205.27126202332, 5309.734513274321, 5334.039872822929, 5360.758765855546, 5380.408155102936, 6564.212294524592, 6592.538522303393, 6595.835104122435, 6606.405423511811, 6611.50413718973, 6065.939340606537, 6270.467010304175, 6329.715162817776, 6337.841553961188, 6337.841553961188, 6387.361263873761, 6402.295747467681, 6414.967925160531, 6488.837779055561, 6514.51141164424, 6090.573659843769, 6093.025115981422, 6103.664298463449, 6105.0, 6120.908186377119, 6202.937970620236, 6228.479987317212, 6292.087647291209, 6293.905591416041, 6303.0, 5848.970755146197, 5881.088431293157, 6028.57799953999, 6055.576109672457, 6132.938670613236, 6113.449789518787, 6155.4767844731905, 6166.691665416622, 6171.290301615371, 6198.6280823150755, 5920.526357891445, 6128.448439640454, 6171.691415429121, 6173.135760993554, 6176.227971503562, 6041.667708276087, 6111.938880611315, 6119.694015299128, 6142.692865356625, 6218.0, 6042.969785151046, 6074.240719910012, 6077.392260773887, 6081.057436097412, 6113.29697084841], "value": 5741.23549382434, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.6930358552991, 826.504097541475, 842.9283510901573, 894.472261365794, 906.0893801729261, 30767.692323076764, 31161.844190779044, 33598.0, 43250.78374608122, 44547.55452445475, 44369.0, 44512.777436112854, 44532.77733611336, 44583.331250031195, 44731.55268447314, 9060.81878362434, 9168.266538676924, 9187.586558604879, 9188.448693078437, 9190.402623829463, 7529.360004399633, 7758.069031716202, 10621.840672389882, 10838.67483975482, 10855.891441085567, 10859.619913303037, 10885.292455990375, 10897.891021089807, 10914.945425272863, 10918.0, 10811.18916081297, 10859.565617375374, 10883.020528152465, 10884.401357925311, 10904.61833835812, 10690.679279621623, 10767.784644307148, 10777.191710621657, 10778.892211077868, 10778.946105269424, 41954.37068443976, 42026.0, 42131.0, 42183.15633687309, 42274.15451690949, 4219.029623186653, 4252.213340531981, 4259.424977628011, 4337.06753048093, 4406.625436837872, 29674.85162574195, 30075.0, 30125.69874301251, 30822.845885770428, 31366.215844603837, 3851.903702407434, 3923.8626648067466, 3926.175503144343, 3932.1742434088874, 4025.6175663311938, 218177.0, 218255.0, 218285.0, 218861.0, 218902.0, 53223.73388133034, 53329.0, 53367.46632533625, 53668.0, 53729.73135134299, 53126.46873531293, 53283.46716532785, 53295.20057199145, 53308.20037699438, 53341.46658533443, 16458.917705411637, 16810.075445850594, 16818.075005874794, 16821.83178168227, 16822.327106915585, 11946.402679866145, 14915.403383864741, 14946.32741526634, 14952.102873827387, 14961.700765984619, 12235.510579576896, 13209.93395033038, 13393.799093013613, 13548.254845983565, 13984.930075349761, 5460.396138548539, 5504.862378440571, 5543.944560554342, 5555.444455554493, 5597.692126933058, 1030.860833787444, 1038.503763339821, 1039.4802598700649, 1052.952617132231, 1061.4480470155477, 27966.0, 28393.43213135726, 28643.427131457254, 28674.0, 28893.27766805847, 1112.0436424674774, 1112.8052590796578, 1125.5666568371237, 1139.7549526851767, 1150.8618965724168, 14962.775558366633, 15084.698306034037, 15150.848491515164, 15215.771763423558, 15380.230988450532, 271584.0, 275769.0, 276530.0, 276875.0, 276912.0, 55889.7205513978, 55958.44041559614, 56115.158272625944, 56182.43817561854, 56238.718806405705, 1501.2643804535699, 1555.3778488604648, 1562.849961426501, 1580.857722804953, 1605.5103193526086, 25557.872210639573, 27365.45269094647, 27480.58779118275, 27590.0, 27727.58408623832, 43307.56692433036, 43316.35025474684, 43353.34969975516, 43461.56538434701, 43486.17339873025, 262991.0, 264806.0, 268220.0, 268549.0, 268672.0, 29977.250568735082, 31305.217369565944, 35696.821515892254, 35766.46350304696, 35884.0, 38398.0, 38417.80791096027, 38705.225895482494, 38766.80616597012, 38799.418008730456, 29568.0, 29690.991062878238, 29764.85117574485, 29807.55288670715, 29887.701122988492, 29732.256693581967, 29960.700392996663, 30064.398712026075, 30397.0, 30633.387332252783, 33801.15497112592, 33861.830690846386, 33935.0, 34111.65888341085, 34157.65842341545, 28559.714402856538, 28577.0, 28718.138455846358, 28734.425311494073, 29139.854300728362, 22466.663000054687, 23427.297181084596, 23506.294811155694, 23619.881900590386, 23827.285181444586], "value": 18448.340963501087, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.5595069226618, 443.7027191781507, 459.1574460864314, 464.35222864104577, 476.7139716170298, 19480.123394447248, 19701.605967880652, 20071.498212544695, 21451.892740536274, 21690.783092169073, 21197.576048479023, 21360.893195534038, 21592.0, 21617.351479455643, 21685.67471487929, 6575.441087507567, 6656.933430665692, 6723.630200338979, 6776.593404395752, 6816.761413350534, 4116.279651061067, 6597.505187110962, 6719.395254427101, 6734.8989765153565, 6745.156855393076, 4381.233284175272, 4404.713693609921, 4788.9521104788855, 7714.190010048948, 7921.36629069673, 7631.12242092161, 7657.0, 7717.72987945422, 7732.1108072571815, 7822.569758663271, 7692.807679807994, 7704.46068775186, 7705.190954949706, 7707.768766937001, 7709.922900770976, 26225.0, 26860.731392686215, 27047.0, 27563.724362756315, 27775.58336624952, 3641.763285386468, 3644.6355364463607, 3696.537932758405, 3757.248550289953, 3787.886363409102, 15656.21718914061, 17149.742753858704, 17429.38997135107, 17899.642007159782, 18383.0, 2275.590393729128, 2330.475642980333, 2380.797632201265, 2398.556267090594, 2400.651905473714, 119876.40061799722, 122881.0, 123250.38374808159, 123445.0, 124431.37784311021, 15718.842811571738, 20456.284030058876, 21481.46296342604, 22192.334229973127, 22845.543089138126, 44351.0, 44483.777581111885, 44631.55368446339, 45105.32342014872, 45354.54645453503, 11584.0, 11736.823947640793, 11820.23168494045, 11828.52685892572, 11960.342181179945, 9548.283878708986, 9647.517624118766, 9729.530231467865, 9847.950760246153, 9863.506824658738, 11165.0, 11362.318260904372, 11514.76970460586, 11582.536698531965, 11680.035160971314, 3924.6664033556895, 4054.6958978076764, 4254.361845723166, 4482.551744825491, 4513.932291015637, 1153.646561015519, 1155.8324043013715, 1158.449736375215, 1178.0810967445407, 1204.6145233525363, 20810.0, 20975.895120524605, 21022.579548409252, 21075.68386474204, 21207.045682944303, 1164.9009834164021, 1165.930044197351, 1181.4329122021306, 1187.8633957094871, 1217.0689422591724, 11190.608328708457, 11413.942930285295, 11435.199536032398, 11441.71395715097, 11541.019013383788, 271356.0, 271493.0, 271552.0, 271862.0, 273065.0, 99272.50363748135, 105825.47087264668, 112996.0, 127624.0, 129040.0, 1397.3083323754806, 1460.2625674034546, 1461.043016823982, 1467.801846750675, 1474.0492382413283, 13191.756511594614, 18097.457076287734, 19664.213431463155, 19689.80310196937, 19760.209591616174, 50869.0, 51993.22010169926, 52465.47534524606, 53251.733741331045, 54399.45600543944, 64192.67903660452, 64703.352966469734, 64934.35065649283, 65251.0, 66365.33634663592, 12637.368131593199, 12705.602383737596, 12747.61757147287, 12754.872451275369, 12868.613941581767, 14014.229217393142, 14124.929375353058, 14145.292735363397, 14236.572902812932, 14292.142471451747, 12188.878111218773, 12288.201266917622, 12514.0, 12539.87460125387, 12925.676858078623, 11314.773704526027, 11488.19582629208, 11537.538498459968, 11542.307461552333, 11589.130815188726, 12407.51734907185, 12497.750044999231, 12559.183653062522, 12724.109312348046, 12917.0, 11281.32312061279, 12020.33888136161, 12596.685082872635, 12692.619221423372, 12766.042546809213, 12432.627021189379, 12460.937695311828, 12485.31330776816, 12490.812637810259, 12535.373231338213], "value": 11763.961732881884, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1570.5680937742113, 1592.7531232658935, 1607.2124658917135, 1611.613212828921, 1622.4402581109514, 10832.350058996455, 11607.419629018557, 11608.361540115291, 11613.245139065966, 11626.825597616042, 11012.339259644426, 11357.716057098576, 11377.089832813375, 11392.886071139286, 11395.088392928566, 2474.381404648838, 2508.648789169518, 2510.1089113364765, 2511.09600543805, 2516.471540976397, 2419.0928401849305, 2463.3841539615096, 2496.0889275414434, 2503.5118151960373, 2505.0, 2288.0, 2294.8623082615013, 2387.904483820645, 2437.061731233479, 2441.292025312657, 2291.495870908414, 2313.606686863229, 2314.722233331994, 2322.3149170994598, 2391.0196819304174, 2288.3706980580305, 2304.8040916522114, 2306.0660432524687, 2314.90740370385, 2319.420144963759, 11413.144014198968, 11740.471678774476, 11767.293962362286, 11768.588099416565, 11779.352135632536, 5773.220615216976, 5862.14998825168, 5894.469497745181, 5905.586608937376, 5915.940840591625, 3529.3294274087843, 3575.5173051637958, 3606.296772129436, 3631.2918980798754, 3632.4914511968345, 1424.5726282115427, 1426.0873041253606, 1484.4507532213042, 2314.155333303349, 2320.164740693349, 14886.851131488655, 15230.923845380703, 16707.33170673184, 17878.106094695344, 17935.641287174185, 4942.258661200849, 5054.519820617035, 5147.382314122255, 5234.712090834964, 5264.10510213257, 14679.55961321162, 14700.485483008044, 14734.852651473348, 14772.48296309624, 14788.260586970608, 6297.433231009231, 6315.0, 6325.272593651789, 6337.271213810379, 6365.840853978595, 5671.404502527258, 5714.542836573064, 5760.481556659837, 5765.308163020464, 5773.278340207474, 4368.60682538573, 4381.38660587518, 4470.374147619336, 4506.121306345297, 4511.210538155809, 1104.9723756906142, 1110.272771334777, 1147.17976646698, 1147.9655610331704, 1148.9942550287194, 1018.7860549284733, 1030.175859312547, 1043.0664555223034, 1045.210865796319, 1046.874375074996, 6961.512694111364, 7104.751333703295, 7116.146062472433, 7237.203907570163, 7251.238619944936, 1037.2894567221476, 1079.8164312066967, 1080.8162612355918, 1099.0, 1128.9548418063187, 4477.1493416250805, 4839.806407743651, 4914.33656456381, 4953.801847926043, 5042.949570504321, 13936.233507157203, 13943.930280348533, 14007.509737159151, 14473.348699308557, 14473.565793026226, 13891.0, 13960.720785584435, 14040.789388159186, 14045.0, 14048.929755351362, 9006.86489702668, 10494.895051049392, 10633.893661063445, 10641.095506882002, 10658.253922225369, 6956.2000369958105, 7564.0, 7698.538087714755, 7718.845623087394, 7842.882356764767, 12543.749125017632, 12557.623271301876, 12632.43154058088, 12848.935755321163, 12927.741445171232, 12720.618381448572, 12794.016927376271, 12804.0, 12864.485420583072, 12961.41673624708, 10178.23663225276, 10190.337628054152, 10270.897291026995, 10274.33216840903, 10281.228907832092, 11078.612248571262, 11106.16703747206, 11157.497912594115, 11170.888291116984, 11219.158563107963, 9413.576389062371, 9460.10129037768, 9512.762180945256, 9541.093596108358, 9681.080297371738, 9178.541072946193, 9363.063693630855, 9377.1091746284, 9520.952395237979, 9585.376950498196, 8383.748487545383, 8793.428427152214, 8924.375293729636, 8928.375013749235, 8930.062343454103, 6330.303666596763, 6428.164338636135, 6478.935210647834, 6494.935050649433, 6588.7364505421165, 6194.4115309045565, 6340.4293613575, 6347.3652634738, 6422.646754428364, 6487.805365839033], "value": 5543.7703463595, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.7717032802287, 436.13209712671784, 447.0165635601677, 451.32076225280946, 454.677179202766, 5213.1919552469335, 5224.68651880887, 5245.108331583635, 5251.973740131303, 5269.735263536752, 5101.7194054327, 5190.273361729361, 5208.322918020662, 5208.374995000596, 5225.137852254374, 4144.295469770132, 4166.0, 4172.666186705056, 4185.979070104645, 4192.4759405074365, 4151.481064866892, 4152.4186613873935, 4156.438880751105, 4160.687948403866, 4182.79086045698, 4177.582241775828, 4178.895527611804, 4182.372644103379, 4213.157368526307, 4233.407322974786, 4043.555208927016, 4089.345704687236, 4155.0, 4184.665226781881, 4205.705600607944, 3765.4351847223134, 3777.4333849922455, 3780.8468417132763, 3803.6576708096127, 3807.7624771949227, 5044.646874718771, 5049.798008079672, 5051.949480505222, 5068.4424713281505, 5084.872878178038, 4678.228092364756, 4737.478877323491, 4807.735574543399, 4828.758562071883, 4906.779194936236, 5496.5983674162835, 5529.612927095105, 5547.611667183299, 5547.639403438804, 5554.805581804618, 4292.79801655537, 4310.159518893817, 4313.309870420718, 4313.633341166004, 4319.552949761834, 5474.507294343528, 5556.388797232301, 5600.719964001824, 5680.8187415047705, 5728.570357223225, 4358.063016451478, 4361.3676016978, 4378.60592546666, 4401.273789824643, 4412.33814927757, 4462.0, 4469.284914413678, 4472.300525800175, 4498.032922921522, 4506.895810526376, 4720.173969555314, 4844.878878028077, 4884.876478409915, 4903.215485522371, 4929.556339929423, 5522.475364840334, 5523.613347065667, 5532.2531458253425, 5534.501894829405, 5542.778288868481, 1705.9573510662085, 1706.385701147586, 1724.6205834716343, 1731.3161301286173, 1735.670222657691, 1391.2278685329738, 1422.8719415252676, 1446.616646588655, 1453.4694836384645, 1460.4450308882556, 5365.275687782099, 5389.51494365509, 5402.945970540323, 5419.268398766116, 5421.966762464076, 5351.81268655603, 5620.241267428844, 5631.71841407928, 5635.3519345275, 5708.543316534668, 5406.972965135228, 5411.188321751769, 5415.972920135374, 5425.674459532441, 5440.483154100354, 2941.749951254167, 2945.6450032985067, 2968.985155074211, 2976.4642364374185, 2977.493826049577, 5602.355729091125, 5762.279715035621, 5825.446582574648, 5849.561282903799, 5892.467958330779, 5526.397344769972, 5642.379338272786, 5768.596198266081, 5770.913436298459, 5779.393163717833, 5789.88420231593, 5832.562557808096, 5839.795607153729, 5840.2115714378015, 5852.326982397079, 5608.355039170385, 5622.690752008679, 5639.210510528611, 5641.348566173891, 5652.88694226105, 5243.606729495227, 5249.818790772046, 5533.446655334435, 5576.0, 5592.937341905025, 5801.3038435388025, 5849.61977471463, 5852.619579727304, 5852.970735146297, 5867.559933005127, 4537.1833070047705, 4649.790759415902, 4670.743109129031, 4672.649551283599, 4692.319613656069, 5591.4688104629995, 5614.933162699074, 5625.774969001194, 5642.520385767256, 5661.518770904519, 5478.863028424321, 5494.890102197854, 5513.8420931603205, 5516.448355164452, 5554.472325129106, 5408.59435542328, 5420.620556561161, 5432.891342173055, 5454.699991500505, 5557.41647127062, 5423.701696406735, 5432.809851655173, 5439.700816454974, 5482.835514934558, 5506.284183056177, 5379.273798037215, 5507.118860982224, 5512.91730624049, 5534.501894829485, 5535.34667119699, 5395.919061214164, 5419.3225846769155, 5425.1554471479885, 5444.8094316698725, 5454.4545545445135], "value": 4380.045031400155, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.6510313323098, 869.2263885142221, 908.7728067983004, 920.9171174594285, 921.5898924978383, 17374.652506949868, 18104.637907241864, 18490.537736556576, 18810.15354309056, 18975.810241897576, 17051.91474042628, 17248.0, 17255.396061137864, 17753.0, 17928.551786205353, 870.7997160653053, 872.4721543466222, 888.62233550741, 890.5235698901089, 891.0109778146252, 876.2376732242926, 879.6261588824756, 887.8135591525787, 888.7911340834899, 889.2397000564521, 911.08436021798, 916.8533034714447, 920.7145784806731, 922.0871337376017, 922.1562270522464, 908.0783005249676, 908.4867050116663, 917.8072604752944, 918.6968300460832, 919.2599957034585, 674.9257581666013, 678.5148618737626, 679.5413096160072, 679.8402375441767, 680.5576375356009, 16432.589185270343, 16620.418285360076, 16919.154042297836, 18590.90704546482, 18652.34716784906, 15166.0, 16122.0, 16177.83822161775, 16270.593235169097, 16522.917385413115, 13467.393967271497, 13785.035047546677, 13810.65473363164, 13861.0, 14072.859271407257, 4041.211963667086, 4061.4720086388875, 4088.693347998882, 4109.91780164398, 4140.130572579732, 69036.0, 69039.65480172567, 91357.54321228417, 92563.07436925679, 93222.5338873308, 12091.274523528617, 12285.262884226975, 12338.568150114703, 12367.814482782765, 12481.500739970299, 21712.674309885366, 22351.329460116223, 22368.776312236994, 22413.215537456435, 23136.884315578314, 9157.038510956387, 9252.629894804268, 9264.44413335202, 9271.860922086174, 9312.906870931203, 4665.113628410659, 4678.8128474861305, 4705.811767529329, 4730.929036064462, 4770.904581908342, 3978.347095068215, 4001.47980762499, 4035.031592417857, 4089.754614723126, 4094.549599544048, 675.9222689390783, 682.4574463301688, 695.5861262548876, 724.2865777209445, 763.9006929099181, 864.6800683747053, 873.9825203496022, 880.1418616848649, 880.7357792662176, 891.5319457284977, 11363.14776391774, 11501.482433290523, 11635.0, 11827.349495777815, 11951.820722689166, 865.6018231613402, 869.5087275689342, 871.4205053639386, 878.6221924572368, 889.9777505562413, 5223.0, 5425.6026517669925, 5503.834884953458, 5567.582431317667, 5579.330480342385, 64427.67786161039, 65755.0, 66737.0, 79471.20528794754, 83828.58085709532, 59736.402635973085, 68381.65809171021, 68866.0, 69294.65352673305, 70118.64940675366, 61744.0, 61757.38242617606, 61859.0, 62325.68837155785, 63481.68259158767, 10752.677419677422, 10917.617883374362, 11078.612248571262, 11254.718632033937, 11290.0, 40950.79524602477, 41521.37717934294, 41727.791361043, 41836.0, 43032.0, 48702.75648621734, 49614.00771984612, 49723.254151188485, 49909.50090499194, 50078.0, 13764.105333153313, 13796.517121900686, 13804.792928105886, 13985.720285594434, 14164.150150990974, 16413.67172656564, 16663.66672666564, 16796.748048779522, 16843.831561684718, 17049.829501704826, 15132.546023619308, 15306.77039844381, 15638.530844074696, 16368.0, 16400.75398869042, 15422.614434639223, 15550.455734049254, 15565.844341556893, 16216.351345946501, 16799.496015119566, 14175.43298268099, 14486.565403037926, 14709.41162353494, 15190.0, 15205.923970380078, 10548.578056877639, 10650.786984260116, 11035.39305338182, 11138.888611113785, 11392.316461012366, 12447.253164810141, 12522.0, 12831.615051548468, 12849.614511564294, 12910.22538647684], "value": 7929.595778145121, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1459.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 112.0, 111.0, 112.0, 111.0, 112.0, 111.0, 111.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 116.0, 117.0, 155.0, 116.0, 116.0, 114.0, 115.0, 136.0, 116.0], "value": 124.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 161.0, 161.0, 163.0, 162.0, 161.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 108.0, 75.0, 76.0, 76.0, 75.0, 89.0, 74.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 102.0, 82.0, 83.0, 81.0, 98.0, 83.0, 82.0, 102.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 149.0, 149.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 54.0, 56.0, 55.0, 56.0, 57.0, 55.0, 54.0, 55.0, 55.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0], "value": 54.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 76.0, 80.0, 74.0, 79.0, 80.0, 76.0, 76.0, 75.0, 76.0], "value": 77.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 120.0, 121.0, 122.0, 122.0, 123.0, 118.0, 120.0, 161.0, 146.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 164.0, 166.0, 158.0, 164.0, 166.0, 164.0, 165.0, 166.0, 167.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 61.0, 60.0, 61.0, 59.0, 60.0, 63.0, 59.0, 60.0], "value": 61.3, "unit": "score"}]}]} 02:01:34 INFO - Return code: 0 02:01:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:01:34 ERROR - # TBPL SUCCESS # 02:01:34 INFO - Running post-action listener: _resource_record_post_action 02:01:34 INFO - [mozharness: 2017-04-25 09:01:34.819046Z] Finished run-tests step (success) 02:01:34 INFO - Running post-run listener: _resource_record_post_run 02:01:34 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 367394816; Read time: 20; Write time: 200288 02:01:34 INFO - TinderboxPrint: CPU usage
13.1% 02:01:34 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 20 02:01:34 INFO - TinderboxPrint: I/O write bytes / time
367,394,816 / 200,288 02:01:34 INFO - TinderboxPrint: CPU idle
8,265.1 (86.9%) 02:01:34 INFO - TinderboxPrint: CPU user
1,200.3 (12.6%) 02:01:34 INFO - TinderboxPrint: Swap in / out
0 / 0 02:01:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110026752; Read time: 0; Write time: 67832 02:01:34 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 266240; Write bytes: 254459904; Read time: 20; Write time: 132252 02:01:35 INFO - Running post-run listener: _upload_blobber_files 02:01:35 INFO - Blob upload gear active. 02:01:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:01:35 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:01:35 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:01:35 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:01:35 INFO - (blobuploader) - INFO - Open directory for files ... 02:01:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:01:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:01:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:36 INFO - (blobuploader) - INFO - Done attempting. 02:01:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:01:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:01:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:37 INFO - (blobuploader) - INFO - Done attempting. 02:01:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:01:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:01:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:38 INFO - (blobuploader) - INFO - Done attempting. 02:01:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:01:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:01:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:01:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:01:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:01:41 INFO - (blobuploader) - INFO - Done attempting. 02:01:41 INFO - (blobuploader) - INFO - Iteration through files over. 02:01:41 INFO - Return code: 0 02:01:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:01:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:01:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5e06e3689c88ad0da83e7cee60788e7405de0d90aac4890cd20131d71099e06142ca34362da2c6ed1bdd7474ce5a7c45032c8e7e39b9d4c1bc476f6fe2073bb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2f24851931c793c2ca2b18e78d82f3fb0c75144619e83fd8dbabe288dad2c9e89458e0d1b3962fdf0659a8fdfe1148590d159edd63047a30bd91955b02f087bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7490516928d263512646441834d252b3f8af6e4ef4619c1aa690e5112b8787f9289d09303eb3c8ce6b89d4793246aef4cc42a955c602fbefb9f5eaf4c3a5d591", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/414c5aad3f36d9c427dcde33e9f38bffb942b28cddfdfecbd26f3022da144585a78a19a3e548f61230d9f7a9ca96f89d7fe03e0dec613828b4a875c810c059ca"} 02:01:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:01:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:01:41 INFO - Contents: 02:01:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5e06e3689c88ad0da83e7cee60788e7405de0d90aac4890cd20131d71099e06142ca34362da2c6ed1bdd7474ce5a7c45032c8e7e39b9d4c1bc476f6fe2073bb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2f24851931c793c2ca2b18e78d82f3fb0c75144619e83fd8dbabe288dad2c9e89458e0d1b3962fdf0659a8fdfe1148590d159edd63047a30bd91955b02f087bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7490516928d263512646441834d252b3f8af6e4ef4619c1aa690e5112b8787f9289d09303eb3c8ce6b89d4793246aef4cc42a955c602fbefb9f5eaf4c3a5d591", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/414c5aad3f36d9c427dcde33e9f38bffb942b28cddfdfecbd26f3022da144585a78a19a3e548f61230d9f7a9ca96f89d7fe03e0dec613828b4a875c810c059ca"} 02:01:41 INFO - Running post-run listener: copy_logs_to_upload_dir 02:01:41 INFO - Copying logs to upload dir... 02:01:41 INFO - mkdir: /builds/slave/test/build/upload/logs 02:01:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.723121 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-04-25 02:01:41.616362) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-25 02:01:41.619608) ========= 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=337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5e06e3689c88ad0da83e7cee60788e7405de0d90aac4890cd20131d71099e06142ca34362da2c6ed1bdd7474ce5a7c45032c8e7e39b9d4c1bc476f6fe2073bb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2f24851931c793c2ca2b18e78d82f3fb0c75144619e83fd8dbabe288dad2c9e89458e0d1b3962fdf0659a8fdfe1148590d159edd63047a30bd91955b02f087bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7490516928d263512646441834d252b3f8af6e4ef4619c1aa690e5112b8787f9289d09303eb3c8ce6b89d4793246aef4cc42a955c602fbefb9f5eaf4c3a5d591", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/414c5aad3f36d9c427dcde33e9f38bffb942b28cddfdfecbd26f3022da144585a78a19a3e548f61230d9f7a9ca96f89d7fe03e0dec613828b4a875c810c059ca"} build_url:https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/GnHRU3prQySfDyuhggf2uA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/5e06e3689c88ad0da83e7cee60788e7405de0d90aac4890cd20131d71099e06142ca34362da2c6ed1bdd7474ce5a7c45032c8e7e39b9d4c1bc476f6fe2073bb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2f24851931c793c2ca2b18e78d82f3fb0c75144619e83fd8dbabe288dad2c9e89458e0d1b3962fdf0659a8fdfe1148590d159edd63047a30bd91955b02f087bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/7490516928d263512646441834d252b3f8af6e4ef4619c1aa690e5112b8787f9289d09303eb3c8ce6b89d4793246aef4cc42a955c602fbefb9f5eaf4c3a5d591", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/414c5aad3f36d9c427dcde33e9f38bffb942b28cddfdfecbd26f3022da144585a78a19a3e548f61230d9f7a9ca96f89d7fe03e0dec613828b4a875c810c059ca"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-25 02:01:41.644826) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 02:01:41.645130) ========= 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=337fe3f4b1febe37b85fccc40000019e-1493109649.724036-2076488763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-25 02:01:41.730994) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-25 02:01:41.731311) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-25 02:01:41.731593) ========= ========= Total master_lag: 0.17 =========