builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1492509878.92 results: success (0) revision: 665b9de58deee6f715514948cecfa4277809d620 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:38.925122) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:38.925605) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:38.925882) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:38.940565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:38.940835) ========= 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=ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-18 03:04:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.0M=0s 2017-04-18 03:04:39 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124951 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:39.079493) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:39.079800) ========= 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=ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017512 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:39.128821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 03:04:39.129180) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 665b9de58deee6f715514948cecfa4277809d620 --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 665b9de58deee6f715514948cecfa4277809d620 --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=ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-18 03:04:39,174 truncating revision to first 12 chars 2017-04-18 03:04:39,174 Setting DEBUG logging. 2017-04-18 03:04:39,174 attempt 1/10 2017-04-18 03:04:39,174 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/665b9de58dee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-18 03:04:40,512 unpacking tar archive at: mozilla-aurora-665b9de58dee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.627336 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 03:04:40.769764) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:40.770094) ========= script_repo_revision: 665b9de58deee6f715514948cecfa4277809d620 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:40.770455) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:40.770713) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 03:04:40.780175) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-18 03:04:40.780528) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:04:40 INFO - MultiFileLogger online at 20170418 03:04:40 in /builds/slave/test 03:04:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:04:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:04:40 INFO - {'append_to_log': False, 03:04:40 INFO - 'base_work_dir': '/builds/slave/test', 03:04:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:04:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:04:40 INFO - 'branch': 'Mozilla-Aurora', 03:04:40 INFO - 'buildbot_json_path': 'buildprops.json', 03:04:40 INFO - 'config_files': ('talos/linux_config.py',), 03:04:40 INFO - 'default_actions': ('clobber', 03:04:40 INFO - 'read-buildbot-config', 03:04:40 INFO - 'download-and-extract', 03:04:40 INFO - 'populate-webroot', 03:04:40 INFO - 'create-virtualenv', 03:04:40 INFO - 'install', 03:04:40 INFO - 'run-tests'), 03:04:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:04:40 INFO - 'download_minidump_stackwalk': True, 03:04:40 INFO - 'download_symbols': 'ondemand', 03:04:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:04:40 INFO - 'tooltool.py': '/tools/tooltool.py', 03:04:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:04:40 INFO - '/tools/misc-python/virtualenv.py')}, 03:04:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:04:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:04:40 INFO - 'gecko_profile': False, 03:04:40 INFO - 'gecko_profile_interval': 0, 03:04:40 INFO - 'installer_path': 'installer.exe', 03:04:40 INFO - 'log_level': 'info', 03:04:40 INFO - 'log_name': 'talos', 03:04:40 INFO - 'log_to_console': True, 03:04:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:04:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:04:40 INFO - 'opt_config_files': (), 03:04:40 INFO - 'pip_index': False, 03:04:40 INFO - 'suite': 'dromaeojs', 03:04:40 INFO - 'system_bits': '32', 03:04:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:04:40 INFO - 'title': 'talos-linux64-ix-035', 03:04:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:04:40 INFO - 'use_talos_json': True, 03:04:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:04:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:04:40 INFO - 'work_dir': 'build'} 03:04:40 INFO - [mozharness: 2017-04-18 10:04:40.928385Z] Running clobber step. 03:04:40 INFO - Running pre-action listener: _resource_record_pre_action 03:04:40 INFO - Running main action method: clobber 03:04:40 INFO - rmtree: /builds/slave/test/build 03:04:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:04:42 INFO - Running post-action listener: _resource_record_post_action 03:04:42 INFO - [mozharness: 2017-04-18 10:04:42.409856Z] Finished clobber step (success) 03:04:42 INFO - [mozharness: 2017-04-18 10:04:42.409953Z] Running read-buildbot-config step. 03:04:42 INFO - Running pre-action listener: _resource_record_pre_action 03:04:42 INFO - Running main action method: read_buildbot_config 03:04:42 INFO - Using buildbot properties: 03:04:42 INFO - { 03:04:42 INFO - "project": "", 03:04:42 INFO - "product": "firefox", 03:04:42 INFO - "who": "cbook@mozilla.com", 03:04:42 INFO - "installer_path": "public/build/target.tar.bz2", 03:04:42 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:04:42 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 03:04:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:04:42 INFO - "stage_platform": "linux64-pgo", 03:04:42 INFO - "basedir": "/builds/slave/test", 03:04:42 INFO - "buildnumber": 1154, 03:04:42 INFO - "platform": "ubuntu64_hw", 03:04:42 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:04:42 INFO - "slavebuilddir": "test", 03:04:42 INFO - "taskId": "cZccuUpSRFyoctugSZTZRQ", 03:04:42 INFO - "branch": "mozilla-aurora", 03:04:42 INFO - "script_repo_revision": "production", 03:04:42 INFO - "revision": "665b9de58deee6f715514948cecfa4277809d620", 03:04:42 INFO - "slavename": "talos-linux64-ix-035", 03:04:42 INFO - "repo_path": "releases/mozilla-aurora" 03:04:42 INFO - } 03:04:42 INFO - Finding installer, test and symbols from parent task. 03:04:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cZccuUpSRFyoctugSZTZRQ'}, attempt #1 03:04:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cZccuUpSRFyoctugSZTZRQ'}, attempt #1 03:04:43 INFO - Task dependencies: ArcLOxTHSjKaHNlB8lSn2Q 03:04:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q'}, attempt #1 03:04:43 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.tar.bz2 03:04:43 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/test_packages.json 03:04:43 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.crashreporter-symbols.zip 03:04:43 INFO - Running post-action listener: _resource_record_post_action 03:04:43 INFO - [mozharness: 2017-04-18 10:04:43.822881Z] Finished read-buildbot-config step (success) 03:04:43 INFO - [mozharness: 2017-04-18 10:04:43.823100Z] Running download-and-extract step. 03:04:43 INFO - Running pre-action listener: _resource_record_pre_action 03:04:43 INFO - Running main action method: download_and_extract 03:04:43 INFO - mkdir: /builds/slave/test/build/tests 03:04:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:04:43 INFO - trying https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.test_packages.json 03:04:43 INFO - Downloading https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:04:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:04:44 INFO - Downloaded 896 bytes. 03:04:44 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:04:44 INFO - Using the following test package requirements: 03:04:44 INFO - {u'common': [u'target.common.tests.zip'], 03:04:44 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:04:44 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:04:44 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:04:44 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:04:44 INFO - u'mozbase': [u'target.common.tests.zip'], 03:04:44 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:04:44 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:04:44 INFO - u'web-platform': [u'target.common.tests.zip', 03:04:44 INFO - u'target.web-platform.tests.zip'], 03:04:44 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:04:44 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:04:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.common.tests.zip 03:04:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:04:44 INFO - Fetch https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.common.tests.zip into memory 03:04:45 INFO - Expected file size: 59547002 03:04:45 INFO - Obtained file size: 59547002 03:04:55 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:04:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.common.tests.zip 03:04:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:04:55 INFO - Fetch https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.common.tests.zip into memory 03:04:56 INFO - Expected file size: 59547002 03:04:56 INFO - Obtained file size: 59547002 03:05:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.talos.tests.zip 03:05:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:05:07 INFO - Fetch https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.talos.tests.zip into memory 03:05:08 INFO - Expected file size: 14404424 03:05:08 INFO - Obtained file size: 14404424 03:05:08 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')]} 03:05:08 INFO - trying https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.tar.bz2 03:05:08 INFO - Downloading https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:05:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:05:09 INFO - Downloaded 66755254 bytes. 03:05:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.tar.bz2 03:05:09 INFO - mkdir: /builds/slave/test/properties 03:05:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:05:09 INFO - Writing to file /builds/slave/test/properties/build_url 03:05:09 INFO - Contents: 03:05:09 INFO - build_url:https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.tar.bz2 03:05:09 INFO - Running post-action listener: _resource_record_post_action 03:05:09 INFO - Running post-action listener: set_extra_try_arguments 03:05:09 INFO - [mozharness: 2017-04-18 10:05:09.823113Z] Finished download-and-extract step (success) 03:05:09 INFO - [mozharness: 2017-04-18 10:05:09.823209Z] Running populate-webroot step. 03:05:09 INFO - Running pre-action listener: _resource_record_pre_action 03:05:09 INFO - Running main action method: populate_webroot 03:05:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:05:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:05:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:05:09 INFO - '--tppagecycles', 03:05:09 INFO - '7'], 03:05:09 INFO - 'tests': ['tsvgm']}}, 03:05:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:05:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:05:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:05:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:05:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:05:09 INFO - 'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:05:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:05:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:05:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:05:09 INFO - 'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['damp', 'tps']}, 03:05:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:05:09 INFO - 'tests': ['damp', 'tps']}, 03:05:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['dromaeo_dom']}, 03:05:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:05:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['basic_compositor_video']}, 03:05:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:05:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['a11yr', 03:05:09 INFO - 'ts_paint', 03:05:09 INFO - 'tpaint', 03:05:09 INFO - 'sessionrestore', 03:05:09 INFO - 'sessionrestore_no_auto_restore', 03:05:09 INFO - 'tabpaint']}, 03:05:09 INFO - 'other-e10s': {'tests': ['a11yr', 03:05:09 INFO - 'ts_paint', 03:05:09 INFO - 'tpaint', 03:05:09 INFO - 'sessionrestore', 03:05:09 INFO - 'sessionrestore_no_auto_restore', 03:05:09 INFO - 'tabpaint']}, 03:05:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:05:09 INFO - 'ts_paint', 03:05:09 INFO - 'tpaint', 03:05:09 INFO - 'sessionrestore', 03:05:09 INFO - 'sessionrestore_no_auto_restore', 03:05:09 INFO - 'tabpaint']}, 03:05:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:05:09 INFO - 'ts_paint', 03:05:09 INFO - 'tpaint', 03:05:09 INFO - 'sessionrestore', 03:05:09 INFO - 'sessionrestore_no_auto_restore', 03:05:09 INFO - 'tabpaint']}, 03:05:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['a11yr', 03:05:09 INFO - 'ts_paint', 03:05:09 INFO - 'tpaint', 03:05:09 INFO - 'sessionrestore', 03:05:09 INFO - 'sessionrestore_no_auto_restore', 03:05:09 INFO - 'tabpaint']}, 03:05:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['a11yr', 03:05:09 INFO - 'ts_paint', 03:05:09 INFO - 'tpaint', 03:05:09 INFO - 'sessionrestore', 03:05:09 INFO - 'sessionrestore_no_auto_restore', 03:05:09 INFO - 'tabpaint']}, 03:05:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['tsvgx', 03:05:09 INFO - 'tsvgr_opacity', 03:05:09 INFO - 'tart', 03:05:09 INFO - 'tscrollx', 03:05:09 INFO - 'cart', 03:05:09 INFO - 'tsvg_static']}, 03:05:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:05:09 INFO - 'tsvgr_opacity', 03:05:09 INFO - 'tart', 03:05:09 INFO - 'tscrollx', 03:05:09 INFO - 'cart', 03:05:09 INFO - 'tsvg_static']}, 03:05:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:05:09 INFO - 'talos_options': ['--disable-e10s'], 03:05:09 INFO - 'tests': ['tp5o']}, 03:05:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:05:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:05:09 INFO - 'talos_options': ['--disable-e10s', 03:05:09 INFO - '--xperf_path', 03:05:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:05:09 INFO - 'tests': ['tp5n']}, 03:05:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:05:09 INFO - 'talos_options': ['--xperf_path', 03:05:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:05:09 INFO - 'tests': ['tp5n']}}, 03:05:09 INFO - 'talos.zip': {'path': '', 03:05:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:05:09 INFO - Running post-action listener: _resource_record_post_action 03:05:09 INFO - [mozharness: 2017-04-18 10:05:09.836276Z] Finished populate-webroot step (success) 03:05:09 INFO - [mozharness: 2017-04-18 10:05:09.836367Z] Running create-virtualenv step. 03:05:09 INFO - Running pre-action listener: _resource_record_pre_action 03:05:09 INFO - Running main action method: create_virtualenv 03:05:09 INFO - Creating virtualenv /builds/slave/test/build/venv 03:05:09 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 03:05:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:05:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:05:09 INFO - Using real prefix '/usr' 03:05:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:05:10 INFO - Installing distribute.............................................................................................................................................................................................done. 03:05:12 INFO - Installing pip.................done. 03:05:12 INFO - Return code: 0 03:05:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:05:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:05:12 INFO - Reading from file tmpfile_stdout 03:05:12 INFO - Output received: 03:05:12 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) 03:05:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:05:12 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')]} 03:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:12 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')]} 03:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:12 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 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:12 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 03:05:12 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 03:05:12 INFO - Using env: {'DISPLAY': ':0', 03:05:12 INFO - 'HOME': '/home/cltbld', 03:05:12 INFO - 'LANG': 'en_US.UTF-8', 03:05:12 INFO - 'LANGUAGE': 'en_US:en', 03:05:12 INFO - 'LOGNAME': 'cltbld', 03:05:12 INFO - 'MAIL': '/var/mail/cltbld', 03:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:12 INFO - 'MOZ_NO_REMOTE': '1', 03:05:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:12 INFO - 'NO_EM_RESTART': '1', 03:05:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:12 INFO - 'PWD': '/builds/slave/test', 03:05:12 INFO - 'SHELL': '/bin/bash', 03:05:12 INFO - 'SHLVL': '1', 03:05:12 INFO - 'TERM': 'linux', 03:05:12 INFO - 'TMOUT': '86400', 03:05:12 INFO - 'USER': 'cltbld', 03:05:12 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:12 INFO - '_': '/tools/buildbot/bin/python'} 03:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:12 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 03:05:12 INFO - Cleaning up... 03:05:12 INFO - Return code: 0 03:05:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:05:12 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')]} 03:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:12 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')]} 03:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:12 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 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:12 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 03:05:12 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 03:05:12 INFO - Using env: {'DISPLAY': ':0', 03:05:12 INFO - 'HOME': '/home/cltbld', 03:05:12 INFO - 'LANG': 'en_US.UTF-8', 03:05:12 INFO - 'LANGUAGE': 'en_US:en', 03:05:12 INFO - 'LOGNAME': 'cltbld', 03:05:12 INFO - 'MAIL': '/var/mail/cltbld', 03:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:12 INFO - 'MOZ_NO_REMOTE': '1', 03:05:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:12 INFO - 'NO_EM_RESTART': '1', 03:05:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:12 INFO - 'PWD': '/builds/slave/test', 03:05:12 INFO - 'SHELL': '/bin/bash', 03:05:12 INFO - 'SHLVL': '1', 03:05:12 INFO - 'TERM': 'linux', 03:05:12 INFO - 'TMOUT': '86400', 03:05:12 INFO - 'USER': 'cltbld', 03:05:12 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:12 INFO - '_': '/tools/buildbot/bin/python'} 03:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:13 INFO - Downloading/unpacking psutil>=3.1.1 03:05:13 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 03:05:13 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 03:05:13 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 03:05:13 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 03:05:13 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 03:05:13 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 03:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:05:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:15 INFO - Installing collected packages: psutil 03:05:15 INFO - Running setup.py install for psutil 03:05:15 INFO - building 'psutil._psutil_linux' extension 03:05:15 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 03:05:15 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 03:05:15 INFO - building 'psutil._psutil_posix' extension 03:05:15 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 03:05:15 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 03:05:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:05:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:05:15 INFO - Successfully installed psutil 03:05:15 INFO - Cleaning up... 03:05:15 INFO - Return code: 0 03:05:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:05:15 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')]} 03:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:15 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')]} 03:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:15 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 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:15 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 03:05:15 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 03:05:15 INFO - Using env: {'DISPLAY': ':0', 03:05:15 INFO - 'HOME': '/home/cltbld', 03:05:15 INFO - 'LANG': 'en_US.UTF-8', 03:05:15 INFO - 'LANGUAGE': 'en_US:en', 03:05:15 INFO - 'LOGNAME': 'cltbld', 03:05:15 INFO - 'MAIL': '/var/mail/cltbld', 03:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:15 INFO - 'MOZ_NO_REMOTE': '1', 03:05:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:15 INFO - 'NO_EM_RESTART': '1', 03:05:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:15 INFO - 'PWD': '/builds/slave/test', 03:05:15 INFO - 'SHELL': '/bin/bash', 03:05:15 INFO - 'SHLVL': '1', 03:05:15 INFO - 'TERM': 'linux', 03:05:15 INFO - 'TMOUT': '86400', 03:05:15 INFO - 'USER': 'cltbld', 03:05:15 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:15 INFO - '_': '/tools/buildbot/bin/python'} 03:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:05:15 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 03:05:15 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 03:05:15 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 03:05:15 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 03:05:15 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 03:05:15 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 03:05:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:05:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:05:18 INFO - Installing collected packages: mozsystemmonitor 03:05:18 INFO - Running setup.py install for mozsystemmonitor 03:05:18 INFO - Successfully installed mozsystemmonitor 03:05:18 INFO - Cleaning up... 03:05:18 INFO - Return code: 0 03:05:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:05: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')]} 03:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05: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')]} 03:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:18 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 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:18 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 03:05:18 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 03:05:18 INFO - Using env: {'DISPLAY': ':0', 03:05:18 INFO - 'HOME': '/home/cltbld', 03:05:18 INFO - 'LANG': 'en_US.UTF-8', 03:05:18 INFO - 'LANGUAGE': 'en_US:en', 03:05:18 INFO - 'LOGNAME': 'cltbld', 03:05:18 INFO - 'MAIL': '/var/mail/cltbld', 03:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:18 INFO - 'MOZ_NO_REMOTE': '1', 03:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:18 INFO - 'NO_EM_RESTART': '1', 03:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:18 INFO - 'PWD': '/builds/slave/test', 03:05:18 INFO - 'SHELL': '/bin/bash', 03:05:18 INFO - 'SHLVL': '1', 03:05:18 INFO - 'TERM': 'linux', 03:05:18 INFO - 'TMOUT': '86400', 03:05:18 INFO - 'USER': 'cltbld', 03:05:18 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:18 INFO - '_': '/tools/buildbot/bin/python'} 03:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:18 INFO - Downloading/unpacking jsonschema==2.5.1 03:05:18 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 03:05:18 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 03:05:18 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 03:05:18 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 03:05:18 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 03:05:18 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 03:05:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:05:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:05:20 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 03:05:20 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 03:05:20 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 03:05:20 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 03:05:20 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 03:05:20 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 03:05:20 INFO - Downloading functools32-3.2.3-2.tar.gz 03:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:05:20 INFO - warning: no files found matching '*.txt' 03:05:20 INFO - no previously-included directories found matching 'build' 03:05:20 INFO - no previously-included directories found matching 'dist' 03:05:20 INFO - no previously-included directories found matching '.git*' 03:05:20 INFO - Installing collected packages: jsonschema, functools32 03:05:20 INFO - Running setup.py install for functools32 03:05:20 INFO - warning: no files found matching '*.txt' 03:05:20 INFO - no previously-included directories found matching 'build' 03:05:20 INFO - no previously-included directories found matching 'dist' 03:05:20 INFO - no previously-included directories found matching '.git*' 03:05:20 INFO - Successfully installed jsonschema functools32 03:05:20 INFO - Cleaning up... 03:05:20 INFO - Return code: 0 03:05:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:05:20 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')]} 03:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:20 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')]} 03:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:20 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 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:20 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 03:05:20 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 03:05:20 INFO - Using env: {'DISPLAY': ':0', 03:05:20 INFO - 'HOME': '/home/cltbld', 03:05:20 INFO - 'LANG': 'en_US.UTF-8', 03:05:20 INFO - 'LANGUAGE': 'en_US:en', 03:05:20 INFO - 'LOGNAME': 'cltbld', 03:05:20 INFO - 'MAIL': '/var/mail/cltbld', 03:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:20 INFO - 'MOZ_NO_REMOTE': '1', 03:05:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:20 INFO - 'NO_EM_RESTART': '1', 03:05:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:20 INFO - 'PWD': '/builds/slave/test', 03:05:20 INFO - 'SHELL': '/bin/bash', 03:05:20 INFO - 'SHLVL': '1', 03:05:20 INFO - 'TERM': 'linux', 03:05:20 INFO - 'TMOUT': '86400', 03:05:20 INFO - 'USER': 'cltbld', 03:05:20 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:20 INFO - '_': '/tools/buildbot/bin/python'} 03:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:05:21 INFO - Cleaning up... 03:05:21 INFO - Return code: 0 03:05:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:21 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 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:21 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 03:05:21 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 03:05:21 INFO - Using env: {'DISPLAY': ':0', 03:05:21 INFO - 'HOME': '/home/cltbld', 03:05:21 INFO - 'LANG': 'en_US.UTF-8', 03:05:21 INFO - 'LANGUAGE': 'en_US:en', 03:05:21 INFO - 'LOGNAME': 'cltbld', 03:05:21 INFO - 'MAIL': '/var/mail/cltbld', 03:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:21 INFO - 'MOZ_NO_REMOTE': '1', 03:05:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:21 INFO - 'NO_EM_RESTART': '1', 03:05:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:21 INFO - 'PWD': '/builds/slave/test', 03:05:21 INFO - 'SHELL': '/bin/bash', 03:05:21 INFO - 'SHLVL': '1', 03:05:21 INFO - 'TERM': 'linux', 03:05:21 INFO - 'TMOUT': '86400', 03:05:21 INFO - 'USER': 'cltbld', 03:05:21 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:21 INFO - '_': '/tools/buildbot/bin/python'} 03:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:21 INFO - Downloading/unpacking blobuploader==1.2.4 03:05:21 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 03:05:21 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 03:05:21 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 03:05:21 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 03:05:21 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 03:05:21 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 03:05:23 INFO - Downloading blobuploader-1.2.4.tar.gz 03:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:05:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:05:23 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 03:05:23 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 03:05:23 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 03:05:23 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 03:05:23 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 03:05:23 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 03:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:05:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:05:23 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 03:05:23 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 03:05:23 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 03:05:23 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 03:05:23 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 03:05:23 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 03:05:24 INFO - Downloading docopt-0.6.1.tar.gz 03:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:05:24 INFO - Installing collected packages: blobuploader, requests, docopt 03:05:24 INFO - Running setup.py install for blobuploader 03:05:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:05:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:05:24 INFO - Running setup.py install for requests 03:05:24 INFO - Running setup.py install for docopt 03:05:24 INFO - Successfully installed blobuploader requests docopt 03:05:24 INFO - Cleaning up... 03:05:24 INFO - Return code: 0 03:05:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:24 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 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:24 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 03:05:24 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 03:05:24 INFO - Using env: {'DISPLAY': ':0', 03:05:24 INFO - 'HOME': '/home/cltbld', 03:05:24 INFO - 'LANG': 'en_US.UTF-8', 03:05:24 INFO - 'LANGUAGE': 'en_US:en', 03:05:24 INFO - 'LOGNAME': 'cltbld', 03:05:24 INFO - 'MAIL': '/var/mail/cltbld', 03:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:24 INFO - 'MOZ_NO_REMOTE': '1', 03:05:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:24 INFO - 'NO_EM_RESTART': '1', 03:05:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:24 INFO - 'PWD': '/builds/slave/test', 03:05:24 INFO - 'SHELL': '/bin/bash', 03:05:24 INFO - 'SHLVL': '1', 03:05:24 INFO - 'TERM': 'linux', 03:05:24 INFO - 'TMOUT': '86400', 03:05:24 INFO - 'USER': 'cltbld', 03:05:24 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:24 INFO - '_': '/tools/buildbot/bin/python'} 03:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:25 INFO - Running setup.py (path:/tmp/pip-lzmzDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:25 INFO - Running setup.py (path:/tmp/pip-JyRZnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:25 INFO - Running setup.py (path:/tmp/pip-sqKfs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:25 INFO - Running setup.py (path:/tmp/pip-WQ58I7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:25 INFO - Running setup.py (path:/tmp/pip-0ztBDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:25 INFO - Running setup.py (path:/tmp/pip-FRI4zB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:25 INFO - Running setup.py (path:/tmp/pip-h41g6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:25 INFO - Running setup.py (path:/tmp/pip-ixzLnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:25 INFO - Running setup.py (path:/tmp/pip-POCDpa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:25 INFO - Running setup.py (path:/tmp/pip-3x6vuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:25 INFO - Running setup.py (path:/tmp/pip-x_UR_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:25 INFO - Running setup.py (path:/tmp/pip-VhIgOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:25 INFO - Running setup.py (path:/tmp/pip-5AC7sN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:26 INFO - Running setup.py (path:/tmp/pip-922THU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:26 INFO - Running setup.py (path:/tmp/pip-MhtZx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:26 INFO - Running setup.py (path:/tmp/pip-JduIhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:26 INFO - Running setup.py (path:/tmp/pip-JQRvRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:05:26 INFO - Running setup.py install for manifestparser 03:05:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:05:26 INFO - Running setup.py install for mozcrash 03:05:26 INFO - Running setup.py install for mozdebug 03:05:26 INFO - Running setup.py install for mozdevice 03:05:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:05:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:05:26 INFO - Running setup.py install for mozfile 03:05:26 INFO - Running setup.py install for mozhttpd 03:05:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:05:26 INFO - Running setup.py install for mozinfo 03:05:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Running setup.py install for mozInstall 03:05:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Running setup.py install for mozleak 03:05:27 INFO - Running setup.py install for mozlog 03:05:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Running setup.py install for moznetwork 03:05:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Running setup.py install for mozprocess 03:05:27 INFO - Running setup.py install for mozprofile 03:05:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Running setup.py install for mozrunner 03:05:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:05:27 INFO - Running setup.py install for mozscreenshot 03:05:27 INFO - Running setup.py install for moztest 03:05:27 INFO - Running setup.py install for mozversion 03:05:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:05:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:05:28 INFO - Cleaning up... 03:05:28 INFO - Return code: 0 03:05:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:28 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')]} 03:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:28 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')]} 03:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:28 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 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:28 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 03:05:28 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 03:05:28 INFO - Using env: {'DISPLAY': ':0', 03:05:28 INFO - 'HOME': '/home/cltbld', 03:05:28 INFO - 'LANG': 'en_US.UTF-8', 03:05:28 INFO - 'LANGUAGE': 'en_US:en', 03:05:28 INFO - 'LOGNAME': 'cltbld', 03:05:28 INFO - 'MAIL': '/var/mail/cltbld', 03:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:28 INFO - 'MOZ_NO_REMOTE': '1', 03:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:28 INFO - 'NO_EM_RESTART': '1', 03:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:28 INFO - 'PWD': '/builds/slave/test', 03:05:28 INFO - 'SHELL': '/bin/bash', 03:05:28 INFO - 'SHLVL': '1', 03:05:28 INFO - 'TERM': 'linux', 03:05:28 INFO - 'TMOUT': '86400', 03:05:28 INFO - 'USER': 'cltbld', 03:05:28 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:28 INFO - '_': '/tools/buildbot/bin/python'} 03:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:05:28 INFO - Running setup.py (path:/tmp/pip-Dsjznu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:05:28 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)) 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:05:28 INFO - Running setup.py (path:/tmp/pip-qVeSil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:05:28 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)) 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:05:28 INFO - Running setup.py (path:/tmp/pip-jJFZIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:05:28 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)) 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:05:28 INFO - Running setup.py (path:/tmp/pip-v6DVf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:05:28 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)) 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:05:28 INFO - Running setup.py (path:/tmp/pip-aofdXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:05:28 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)) 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:05:28 INFO - Running setup.py (path:/tmp/pip-cg8xLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:05:28 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)) 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:05:28 INFO - Running setup.py (path:/tmp/pip-MkoA4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:05:28 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)) 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:05:28 INFO - Running setup.py (path:/tmp/pip-vPXIcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:05:28 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)) 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:05:28 INFO - Running setup.py (path:/tmp/pip-moOgHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:05:28 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)) 03:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:05:28 INFO - Running setup.py (path:/tmp/pip-3X3SoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:05:29 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)) 03:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:05:29 INFO - Running setup.py (path:/tmp/pip-1CG971-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:05:29 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)) 03:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:05:29 INFO - Running setup.py (path:/tmp/pip-mIqfOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:05:29 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)) 03:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:05:29 INFO - Running setup.py (path:/tmp/pip-3vL940-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:05:29 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)) 03:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:05:29 INFO - Running setup.py (path:/tmp/pip-Jc2SOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:05:29 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)) 03:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:29 INFO - Running setup.py (path:/tmp/pip-3vohHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:05:29 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)) 03:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:05:29 INFO - Running setup.py (path:/tmp/pip-dpEkEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:05:29 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)) 03:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:05:29 INFO - Running setup.py (path:/tmp/pip-Hpa6I7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:05:29 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)) 03:05:29 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)) 03:05:29 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)) 03:05:29 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)) 03:05:29 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)) 03:05:29 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)) 03:05:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:05:31 INFO - Downloading blessings-1.6.tar.gz 03:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:05:31 INFO - Installing collected packages: blessings 03:05:31 INFO - Running setup.py install for blessings 03:05:31 INFO - Successfully installed blessings 03:05:31 INFO - Cleaning up... 03:05:31 INFO - Return code: 0 03:05:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:05:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:32 INFO - Reading from file tmpfile_stdout 03:05:32 INFO - Current package versions: 03:05:32 INFO - argparse == 1.2.1 03:05:32 INFO - blessings == 1.6 03:05:32 INFO - blobuploader == 1.2.4 03:05:32 INFO - docopt == 0.6.1 03:05:32 INFO - functools32 == 3.2.3-2 03:05:32 INFO - jsonschema == 2.5.1 03:05:32 INFO - manifestparser == 1.1 03:05:32 INFO - mozInstall == 1.12 03:05:32 INFO - mozcrash == 1.0 03:05:32 INFO - mozdebug == 0.1 03:05:32 INFO - mozdevice == 0.49 03:05:32 INFO - mozfile == 1.2 03:05:32 INFO - mozhttpd == 0.7 03:05:32 INFO - mozinfo == 0.9 03:05:32 INFO - mozleak == 0.1 03:05:32 INFO - mozlog == 3.4 03:05:32 INFO - moznetwork == 0.27 03:05:32 INFO - mozprocess == 0.25 03:05:32 INFO - mozprofile == 0.28 03:05:32 INFO - mozrunner == 6.13 03:05:32 INFO - mozscreenshot == 0.1 03:05:32 INFO - mozsystemmonitor == 0.3 03:05:32 INFO - moztest == 0.8 03:05:32 INFO - mozversion == 1.4 03:05:32 INFO - psutil == 3.1.1 03:05:32 INFO - requests == 1.2.3 03:05:32 INFO - wsgiref == 0.1.2 03:05:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:32 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 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:32 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 03:05:32 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 03:05:32 INFO - Using env: {'DISPLAY': ':0', 03:05:32 INFO - 'HOME': '/home/cltbld', 03:05:32 INFO - 'LANG': 'en_US.UTF-8', 03:05:32 INFO - 'LANGUAGE': 'en_US:en', 03:05:32 INFO - 'LOGNAME': 'cltbld', 03:05:32 INFO - 'MAIL': '/var/mail/cltbld', 03:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:32 INFO - 'MOZ_NO_REMOTE': '1', 03:05:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:32 INFO - 'NO_EM_RESTART': '1', 03:05:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:32 INFO - 'PWD': '/builds/slave/test', 03:05:32 INFO - 'SHELL': '/bin/bash', 03:05:32 INFO - 'SHLVL': '1', 03:05:32 INFO - 'TERM': 'linux', 03:05:32 INFO - 'TMOUT': '86400', 03:05:32 INFO - 'USER': 'cltbld', 03:05:32 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:32 INFO - '_': '/tools/buildbot/bin/python'} 03:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:32 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)) 03:05:32 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)) 03:05:32 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)) 03:05:32 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)) 03:05:32 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)) 03:05:32 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)) 03:05:32 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)) 03:05:32 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)) 03:05:32 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)) 03:05:32 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)) 03:05:32 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)) 03:05:32 INFO - Cleaning up... 03:05:32 INFO - Return code: 0 03:05:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:05:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x281e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2916a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2abbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, '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': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', '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 03:05:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:05:32 INFO - Using env: {'DISPLAY': ':0', 03:05:32 INFO - 'HOME': '/home/cltbld', 03:05:32 INFO - 'LANG': 'en_US.UTF-8', 03:05:32 INFO - 'LANGUAGE': 'en_US:en', 03:05:32 INFO - 'LOGNAME': 'cltbld', 03:05:32 INFO - 'MAIL': '/var/mail/cltbld', 03:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:32 INFO - 'MOZ_NO_REMOTE': '1', 03:05:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:32 INFO - 'NO_EM_RESTART': '1', 03:05:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:32 INFO - 'PWD': '/builds/slave/test', 03:05:32 INFO - 'SHELL': '/bin/bash', 03:05:32 INFO - 'SHLVL': '1', 03:05:32 INFO - 'TERM': 'linux', 03:05:32 INFO - 'TMOUT': '86400', 03:05:32 INFO - 'USER': 'cltbld', 03:05:32 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:32 INFO - '_': '/tools/buildbot/bin/python'} 03:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:05:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:05:32 INFO - Cleaning up... 03:05:32 INFO - Return code: 0 03:05:32 INFO - Running post-action listener: _resource_record_post_action 03:05:32 INFO - Running post-action listener: _start_resource_monitoring 03:05:32 INFO - Starting resource monitoring. 03:05:32 INFO - [mozharness: 2017-04-18 10:05:32.540390Z] Finished create-virtualenv step (success) 03:05:32 INFO - [mozharness: 2017-04-18 10:05:32.540856Z] Running install step. 03:05:32 INFO - Running pre-action listener: _resource_record_pre_action 03:05:32 INFO - Running main action method: install 03:05:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:05:32 INFO - Reading from file tmpfile_stdout 03:05:32 INFO - Detecting whether we're running mozinstall >=1.0... 03:05:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:05:32 INFO - Reading from file tmpfile_stdout 03:05:32 INFO - Output received: 03:05:32 INFO - Usage: mozinstall [options] installer 03:05:32 INFO - Options: 03:05:32 INFO - -h, --help show this help message and exit 03:05:32 INFO - -d DEST, --destination=DEST 03:05:32 INFO - Directory to install application into. [default: 03:05:32 INFO - "/builds/slave/test"] 03:05:32 INFO - --app=APP Application being installed. [default: firefox] 03:05:32 INFO - mkdir: /builds/slave/test/build/application 03:05:32 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'] 03:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:05:49 INFO - Reading from file tmpfile_stdout 03:05:49 INFO - Output received: 03:05:49 INFO - /builds/slave/test/build/application/firefox/firefox 03:05:49 INFO - Running post-action listener: _resource_record_post_action 03:05:49 INFO - [mozharness: 2017-04-18 10:05:49.660771Z] Finished install step (success) 03:05:49 INFO - [mozharness: 2017-04-18 10:05:49.661019Z] Running run-tests step. 03:05:49 INFO - Running pre-action listener: _resource_record_pre_action 03:05:49 INFO - Running main action method: run_tests 03:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:05:49 INFO - Python 2.7.3 03:05:49 INFO - Return code: 0 03:05:49 INFO - grabbing minidump binary from tooltool 03:05:49 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')]} 03:05:49 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 0x2ab9290>, '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 0x2ab9b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aba040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:05:49 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 03:05:49 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 03:05:49 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 03:05:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:05:49 INFO - Return code: 0 03:05:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:05:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:05:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:05:49 INFO - ENV: RUST_BACKTRACE is now 1 03:05:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:05:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 03:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-035 --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 03:05:49 INFO - Using env: {'DISPLAY': ':0', 03:05:49 INFO - 'HOME': '/home/cltbld', 03:05:49 INFO - 'LANG': 'en_US.UTF-8', 03:05:49 INFO - 'LANGUAGE': 'en_US:en', 03:05:49 INFO - 'LOGNAME': 'cltbld', 03:05:49 INFO - 'MAIL': '/var/mail/cltbld', 03:05:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:05:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:05:49 INFO - 'MOZ_NO_REMOTE': '1', 03:05:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:05:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:05:49 INFO - 'NO_EM_RESTART': '1', 03:05:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:05:49 INFO - 'PWD': '/builds/slave/test', 03:05:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:05:49 INFO - 'RUST_BACKTRACE': '1', 03:05:49 INFO - 'SHELL': '/bin/bash', 03:05:49 INFO - 'SHLVL': '1', 03:05:49 INFO - 'TERM': 'linux', 03:05:49 INFO - 'TMOUT': '86400', 03:05:49 INFO - 'USER': 'cltbld', 03:05:49 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803', 03:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:05:49 INFO - '_': '/tools/buildbot/bin/python'} 03:05:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--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 03:05:49 INFO - mozversion application_buildid: 20170418085103 03:05:49 INFO - mozversion application_changeset: 665b9de58deee6f715514948cecfa4277809d620 03:05:49 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:05:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:05:49 INFO - mozversion application_name: Firefox 03:05:49 INFO - mozversion application_remotingname: firefox-dev 03:05:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:05:49 INFO - mozversion application_vendor: Mozilla 03:05:49 INFO - mozversion application_version: 54.0a2 03:05:49 INFO - mozversion platform_buildid: 20170418085103 03:05:49 INFO - mozversion platform_changeset: 665b9de58deee6f715514948cecfa4277809d620 03:05:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:05:49 INFO - mozversion platform_version: 54.0a2 03:05:49 INFO - using testdate: 1492509949 03:05:49 INFO - actual date: 1492509949 03:05:49 INFO - starting webserver on 'localhost:36214' 03:05:49 INFO - SUITE-START | Running 2 tests 03:05:49 INFO - TEST-START | dromaeo_css 03:05:49 INFO - operating with platform_type : linux_ 03:05:49 INFO - Initialising browser for dromaeo_css test... 03:05:49 INFO - TEST-INFO | started process 4888 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSEvV0V/profile http://localhost:36214/getInfo.html) 03:05:53 INFO - PROCESS | 4888 | __metrics Screen width/height:1600/1200 03:05:53 INFO - PROCESS | 4888 | colorDepth:24 03:05:53 INFO - PROCESS | 4888 | Browser inner width/height: 1024/697 03:05:53 INFO - PROCESS | 4888 | __metrics 03:05:54 INFO - PROCESS | 4888 | 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" 03:05:54 INFO - PROCESS | 4888 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:05:55 INFO - TEST-INFO | 4888: exit 0 03:05:55 INFO - Browser initialized. 03:05:55 INFO - Running cycle 1/1 for dromaeo_css test... 03:05:55 INFO - TEST-INFO | started process 4982 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSEvV0V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:06:07 INFO - PROCESS | 4982 | RSS: Main: 139956224 03:06:07 INFO - PROCESS | 4982 | 03:09:08 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:36214/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36214/tests/dromaeo/cssquery-ext.html) 03:09:10 INFO - PROCESS | 4982 | RSS: Main: 232808448 03:09:10 INFO - PROCESS | 4982 | 03:10:57 INFO - PROCESS | 4982 | ************************* 03:10:57 INFO - PROCESS | 4982 | A coding exception was thrown and uncaught in a Task. 03:10:57 INFO - PROCESS | 4982 | 03:10:57 ERROR - PROCESS | 4982 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:10:57 INFO - PROCESS | 4982 | Full stack: 03:10:57 INFO - PROCESS | 4982 | ************************* 03:10:57 INFO - PROCESS | 4982 | ************************* 03:10:57 INFO - PROCESS | 4982 | A coding exception was thrown and uncaught in a Task. 03:10:57 INFO - PROCESS | 4982 | 03:10:57 ERROR - PROCESS | 4982 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:10:57 INFO - PROCESS | 4982 | Full stack: 03:10:57 INFO - PROCESS | 4982 | ************************* 03:10:57 INFO - PROCESS | 4982 | ************************* 03:10:57 INFO - PROCESS | 4982 | A coding exception was thrown and uncaught in a Task. 03:10:57 INFO - PROCESS | 4982 | 03:10:57 ERROR - PROCESS | 4982 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:10:57 INFO - PROCESS | 4982 | Full stack: 03:10:57 INFO - PROCESS | 4982 | ************************* 03:10:57 INFO - PROCESS | 4982 | ************************* 03:10:57 INFO - PROCESS | 4982 | A coding exception was thrown and uncaught in a Task. 03:10:57 INFO - PROCESS | 4982 | 03:10:57 ERROR - PROCESS | 4982 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:10:57 INFO - PROCESS | 4982 | Full stack: 03:10:57 INFO - PROCESS | 4982 | ************************* 03:12:12 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:36214/tests/dromaeo/cssquery-ext.html (next: http://localhost:36214/tests/dromaeo/cssquery-jquery.html) 03:12:12 INFO - PROCESS | 4982 | RSS: Main: 221532160 03:12:12 INFO - PROCESS | 4982 | 03:15:14 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:36214/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36214/tests/dromaeo/cssquery-mootools.html) 03:15:15 INFO - PROCESS | 4982 | RSS: Main: 169308160 03:15:15 INFO - PROCESS | 4982 | 03:18:16 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:36214/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36214/tests/dromaeo/cssquery-prototype.html) 03:18:17 INFO - PROCESS | 4982 | RSS: Main: 170876928 03:18:17 INFO - PROCESS | 4982 | 03:21:20 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:36214/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36214/tests/dromaeo/cssquery-yui.html) 03:21:20 INFO - PROCESS | 4982 | RSS: Main: 177868800 03:21:20 INFO - PROCESS | 4982 | 03:24:23 INFO - PROCESS | 4982 | Cycle 1(1): loaded http://localhost:36214/tests/dromaeo/cssquery-yui.html (next: http://localhost:36214/tests/dromaeo/cssquery-dojo.html) 03:24:23 INFO - PROCESS | 4982 | RSS: Main: 271294464 03:24:23 INFO - PROCESS | 4982 | 03:24:23 INFO - PROCESS | 4982 | __start_tp_report 03:24:23 INFO - PROCESS | 4982 | _x_x_mozilla_page_load 03:24:23 INFO - PROCESS | 4982 | _x_x_mozilla_page_load_details 03:24:23 INFO - PROCESS | 4982 | |i|pagename|runs| 03:24:23 INFO - PROCESS | 4982 | |0;dojo.html;3403.3873902697514;3437.1407148212948;3448.051785758916;3466.4973578831086;3495.877644282449;5796.217510636063;5847.327557330903;5864.530837532998;5864.648121112737;5876.44173803488;5777.133429985504;5861.5310775138;5867.76528938842;5875.118732190173;5883.617564858281;5303.363596368432;5322.76047577858;5322.787088516455;5356.143017117262;5421.2952316199035;5169.508896654812;5183.766730497136;5223.294855194538;5369.033573956686;5376.193570964348;5332.440093790155;5354.357477102753;5354.973225133869;5372.650777699437;5382.407935127133;5342.839714808542;5361.946380536184;5365.865853353697;5370.731463426832;5393.2449457076045;5076.949230507685;5105;5262.368515778131;5330.200469929503;5387.4073851876265;5649.435056494318;5682.488576028178;5778.133280007991;5829.621074630136;5856.502197313191;6195.504359651217;6233.688315584202;6243.56295059346;6251.12484252205;6252.718627661766;6036.034234522455;6278.811635650938;6293.433590976788;6306.558540902139;6310.211223597051;5746.626469279525;5751.597388182829;5796.623219490762;5798.710064496759;5802.33273173582;6330.0821380899515;6366.204224471941;6366.26787919386;6372.490200783926;6373.490120790326;6062.393760623902;6135.141080248769;6140.048292514667;6140.785072522441;6142.201513803177;6026.5178785697035;6065.120557519135;6113.663748493786;6118.4668989547035;6129.05316799051;6201.658908760061;6243.250809902841;6252.187215661935;6252.218472690914;6255.186825712719;6003.249593800775;6041.516678665696;6050.485708714808;6119.207540218003;6122.5408094392205;4693.741844198602;4746.264329029006;4755.120302744003;4762.333273341735;4764.76176191196;4135.193637240709;4137.1518838638;4139.4204811326435;4180.352045432963;4263.59495847894;6080.969595151996;6087.604305720114;6090.147379366892;6105.358937311518;6127.356627554132;6230.376962303733;6242.43818056368;6261.655608941461;6270.498360131179;6284.025975973731;5848.941510584925;5863.501602363847;5976.163337132805;6085.878282434415;6094.451499365078;5303.840884773463;5305.65513241638;5325.440828713007;5332.200169974457;5339.546138578186;6543.149390579194;6548.3124271950755;6575.671216439159;6577.21073471177;6583.341665833378;5978.461938425388;6182.505399568114;6222.186824892722;6249.812505624838;6271.686415679289;6361.395667411589;6481.319461456667;6514.4788416925585;6525.771597994048;6555.672216389257;6149.938500614937;6151.538634602333;6161.599496032743;6165.167702360124;6170.290416602148;6210.534209934363;6266.27937787142;6270;6272.09054687077;6273.529485288713;5875.471207591338;5930.140129681258;5970.194023806731;5975.25309336333;6095.786647467317;6074.51403887679;6077.361877002851;6081;6083.148359229623;6115.785947491817;5835.095560188178;5859.472647461749;6039.335673076046;6052.334243233329;6055.818325450244;5979.910301345571;6041.6375017499095;6107.6640784757265;6139.737467782926;6169.105479705508;6028.87942241144;6029.668368239789;6030.216071910623;6033.215681961317;6055.697215139314 03:24:23 INFO - PROCESS | 4982 | |1;ext.html;771.7877583664491;823.1521532821195;843.4306842881053;881.4138597832442;884.2086332732209;30355.848220758893;41914.37128443072;42469.57530424695;42566.36150457746;42619.57380426195;43899.56100438994;44061.559384406144;44068.55931440693;44101.55898441015;44109.33835992463;7637.15991240963;9146.679866204684;9282.953585232099;9289.628414863397;9292.72121836346;7457.738979135731;10463.73840653986;10663.98692124251;10666.573337066548;10673.893261067406;10877.347359158437;10887.183461240398;10895.019448249655;10901.073408760265;10903.127749779998;10784.5686172553;10812.080973117256;10853.51159197834;10859.782804343948;10870.07604353627;10586.788264234672;10592.576296948113;10608.522616482276;10625.840612390743;10643.680689579325;41179.176416471804;41189.176216475505;41248.58751412507;41257.587424125675;41262.79368603138;4208.684348673831;4212.662986961036;4269.6370808481315;4338.587834155782;4372.2130016597;28682.282942926384;29113.708862911313;29132.41735165306;29571.70428295711;30291.545626815616;4276.657867370603;4367.192069467127;4369.781510924441;4373.540778218273;4447.954730638297;217583.91208044018;217926;218045;218129;218761;53359.46640533623;53386.466135338924;53441;53472.465275347524;53560;54567.18149227765;55143.172852407246;55148.72425637926;55211.171832422544;55247;15829.445969391016;16338.346466141458;16370.345186192419;16373.263203155648;16384;12749.808752868714;12863.678408039874;14534.781978270334;14536.563903082924;14554.199519026555;12657.746845063048;13028.47886084565;13230.404631791593;13262.403191856389;13365.26491043002;5351.759170837322;5383.946160538423;5387.3804512481565;5422.75597598109;5457.072297709399;968.6803354892868;982.1553464020936;988.3032462114336;988.7132731507783;1005.7385079879137;27888.442231155263;27965.580516292273;28181.859090704824;28503.857480712464;28640.570391444144;1029.1098200057047;1060.7189094889861;1068.9037986581245;1069.5187165775433;1075.2526993739432;14169.78745318821;15206.286051324125;15229.6192595186;15409.152496612569;15449.5365139046;285182;287314;288599;289639;290325;54358.45641543613;54512.454875451534;54543;54753.45246547484;54768.45231547713;1452.368219824401;1457.8104846369904;1481.126135580013;1514.3942423030876;1528.6331280492825;25189.370265743502;26649;27120.457590848466;27162.592561111203;27229.319267018484;41644;41702.58297416987;41712.58287417208;41791.58208417877;41798;271876;272947;273256;274442;274485;29847.70152298449;31473.8426307867;34516.82741586276;34519.82740086384;34670.47994280138;43571.34642980294;43611.127777444904;43685;45052.32421513746;45201.54798452105;33545.66454335523;34003.65996340005;34081.48877766786;34145.48781768325;34289.48565771466;33404.33191336208;33468.49797252994;33599.66400336063;33614.32771344608;33805.66194338025;38350.23299533938;38370.616293836705;38774.2245155101;39113.608863910995;39759.40360894531;31853.840730796197;32247.838760806048;32256.677433225366;32307.51538726968;32452.188695281864;25590.744092558834;25983.740162598133;26050.478990420466;26076.8696156518;26249.343766405993 03:24:23 INFO - PROCESS | 4982 | |2;jquery.html;414.8340663734504;468.9484156742758;484.3316223611418;488.00690594639906;492.8767808047988;17891.373801916936;19145.52136196596;19792.406227813153;20423.387298381036;20607.690884636744;15277.465288714897;21827.563448731056;22496.212632557865;22522;23028;6829.3170682931805;6926.549774264682;6948.791536253921;7078.327558881898;7109.537880037781;6873.106496155493;6970.442364610844;6978.9302106978785;6984.1619005719385;6993.370596646301;6860.279670634562;6924.030635711005;6925.653717314114;6979.09271794666;7034.613096279702;6827.180738311435;6972.442204623618;7030.156381234259;7081.89377159343;7082.64586770662;6874.2782007889455;6874.9656251718425;6879.277675844014;6880.931190688079;6883;25792.61311080335;25922.351941201432;26412.867935660197;26519.071832485963;26843;3043.04144194578;3052.1759125036115;3076.215565030903;3110.8444577771247;3132.8746850126195;16382.508524744158;16399.83600164007;16771.91614041934;17101.828981710147;17345;2272.0684519347155;2355.057976809273;2357.811375089989;2358;2392.234484964812;84303.15696843015;85024;85420;90227;101458.99999999926;19030.33393831209;20046.39860804178;20284.59430811405;20451.386458406272;20501.58996820055;37740;38060.809695951895;38228.235435291135;38228.8088559561;38326.61673383286;9454.385464944757;10450;10972.561097556008;11309.71725706864;11539.365334906493;9431.717048488556;9556.331056825955;9563;9781.266405019653;9794.167495762798;10358.844617330746;10536.420496872746;10643.627473038407;10705.732356691145;10916.454177291103;3697.3344797936093;3926.587708290588;3945.901352466154;4050.9189816204102;4174.352975288835;942.6135284533308;945.7399215215734;948.5636607160645;956.516958935733;958.0515289862985;19739;20740.481487962923;21360.57278854414;21695.132194712034;21709.131634734433;1070.7911957168278;1083.815751322277;1088.5047303477058;1108.8170451875512;1123.224974767408;11428.142889283337;11618;11739.88260117405;11747.942685158378;11859.466324015268;449815;454620;677155;678834;678834;90441;100253.49873250588;117093;125752.37123814323;127273;1333.5066025570602;1343.5700575815645;1357.83705955286;1384.0450089438255;1384.9030567860152;18836.52908677294;19808.90095549513;19859.702104468735;19885.801141988395;19892.60214795667;51973.7401312991;52086.21870672019;52720;53868.46131538635;54529.72735136299;71528.28471715424;72433;72849.6357518209;73028.63485682537;73853;12602.054845886412;12721.363931803187;12733.745325093632;12812.231266124063;12852.485900564247;12871.485140594647;14343.713125737635;14440.350184241524;14653.340599673253;14716;12234.571789987307;12297;12330.63008109758;12383.938080309901;12526.185797923106;11801.704957376134;12059.879401206226;12060.577879774166;12069.094817888868;12361.814572781595;12709.4280757368;12837.550685725955;12847.743045139232;12863.549775757803;12984.091113621957;12527.310997895203;12769.297688627215;12833.550825721055;13047.543335983195;13103.2138071716;12580.433880475217;12613.87386126127;12635.557755478514;12698.873011269769;13042.087053906136 03:24:23 INFO - PROCESS | 4982 | |3;mootools.html;1549.9457518986837;1555.9222038898051;1561.8047744031992;1571.6149543361873;1575.5037163293568;11027.944860275707;11634.185607007503;11642.708932276697;11664;11667.358295293756;11084.889151108506;11334.716632084203;11618.419079046056;11663.41682915853;11685.941570292158;2427.8786060696984;2469.5307891500647;2471.468634243637;2473.369290830836;2474.8762561871927;2391.0632387765877;2409.229046705055;2452.91414800482;2453.362125847275;2453.926382208537;2386.367612582667;2455.8526488410753;2458.8893499792553;2477.55404027275;2480.7395223501544;2352.952940941189;2411.9397015074587;2437.2200895713377;2441.7924476419525;2446.804255659543;2409.795167410772;2426.5632186206476;2434.415740222352;2437.439388940536;2437.8171637127284;11461.255018423859;11664.183507154503;11671.474783634756;11737.823932641017;11759.17685761997;5509.311336082989;5598.076317407623;5615.101583746581;5665.518430933375;5669.518090962273;3483.6168021517865;3611.043073585502;3625.2024554597947;3636.5454318210222;3714.220013797106;1389.298404305819;1420.843707192208;1440.7982882396473;1467.6697743007844;1474.787818272591;17205.655886882192;17477.650446991116;17993.820061799477;17998.730019049595;18147.364842230458;4803.735794531264;4820.060088282751;4823.855284341475;4871.269309603588;4936;13322.333883305795;13349.733005340033;13384.464621415229;13392.866071339356;13401.262930538915;6053;6056.757729690763;6059.818205453844;6060.545459090586;6061.545384096122;5275.393329767049;5565.276514053147;5621.353544342372;5622.522085622686;5626.915596266059;4115.300398932189;4152.231837110145;4160.1471698301775;4204.642605378577;4207.474065741782;1041.6354276003337;1042.9061384475433;1046.5604446132643;1050.0916707048348;1060.6658902445859;948.9810203795886;970.0832713086066;973.2846357926918;979.1628157925014;988.110700369673;6377.266614339317;6433.1636887205295;6526.2494813096155;6570.257628776889;6615.801525954229;1006.5973610555836;1010.1767059846202;1024.6874703215442;1047.9895201048043;1057.0592172966067;4036.8183431745642;4216.7259128156575;4258.723182993095;4320.719153255029;4393.648508119343;14448.277586120652;15019.09885406879;15034.398624054915;15064.924675376553;15071.246437678072;12528.874711252953;14069.437222510986;14549.12705237689;14975.925120374328;15158.621034474227;9481.525923703788;10461.320014199053;10554.788904222027;10603.734906627396;10679.679609611723;7057.249011226146;7116.750913717995;7128.501004929605;7165.104361954755;7227.855442891007;12993.285369304778;13454.730905382034;13588.116772409761;13600.115992460458;13711.314434278047;12670;13879.375428105961;14050.508232212233;14051.156930584199;14083.084599501;9877.30858839903;10136.341137826017;10172.847407289044;10186;10187.949060254652;10926.508307126358;10972.561097556008;11051.557937682725;11053.557857685602;11053.889461105608;9207.355485115977;9292.39599426035;9364.250859931326;9390.859137112811;9464.668736594185;9158.908410915805;9169.35814492979;9212.723618291462;9313.767155821159;9358.391704539183;8229.629666665134;8253.83492330162;8315.708950186714;8346.540940248344;8416.537090460084;5505.421930697219;5677.318721753416;5713.742881570256;5725.227094342377;6081.513478921587;5862.530997520103;5901.262342207224;5957.940420595739;5959.523238141025;6069.666168360783 03:24:23 INFO - PROCESS | 4982 | |4;prototype.html;407.7361799541174;421.17449798395137;431.67192933370666;435.65365534400166;440.05388414907964;5292.2061690746405;5308.654937429064;5323.653962492435;5327.840164795058;5336.4129945705945;5218.39988401334;5248.029114613799;5256.737163141846;5259.368875734907;5317;4307.935380969288;4360.887973566736;4423.823047078113;4424.513303536608;4450.443694538183;4427.203103441379;4445.555444455545;4447.266201076818;4454.198244316021;4479.603412174487;4453.933191002121;4477.283634618461;4496.460424749019;4496.595306422421;4499.505054444008;4393.1433370492605;4434.625266167498;4439.64482841372;4463.072496203353;4471;4126.938095928564;4136.172765446892;4148.771817550033;4150.83396664133;4159.629011463467;5020.297158397827;5082.593392528625;5132.743362831843;5138.897222055575;5159.948400515985;4906.582940450066;5102.974485127587;5137.280780690707;5154.201098829687;5167.948320516822;5471.890562188773;5480.958617862593;5496.032142642968;5496.1755736639425;5507.752151153208;4537.8411755588395;4563.174730107983;4598.149342371672;4622.260438329851;4651.5813576778255;5687.260656114679;5706.714664266811;5712.857178570527;5754.884902301972;5771.249737534135;4671.346011558385;4700.365450664185;4700.824793801549;4770.356001939694;4819.975900120477;4828.299896514985;4868.02639472107;4885.60915126789;4894.241392584155;4917.385326834145;4990.226514890198;5056.82301119459;5083.237514372874;5098.388193416739;5157.994191132693;5492.642978206404;5505.862353441196;5506.311711036121;5507.201455788968;5551.3893471718075;1632.4449687106442;1643.9342426302815;1654.9089800060872;1657.9917100414661;1663.3180396037808;1215.9907276960103;1265.7405231927432;1271.4742309228961;1271.6947932496134;1308.8494823095466;5471.316085489314;5486.561075113981;5499.230107784833;5548.223248745179;5641.64600495878;5455.263539422207;5499.890002199933;5520.420355862657;5604.915926261109;5659.405762394971;5172.241437911128;5441.44918698177;5485.945140548623;5511.917321240185;5522.475364840334;2978.8233647709467;2992.787920892042;2996.9850150749107;2997.190758495194;3025.561293612414;5666.518345940642;5883.470487656046;5907.527397808165;5918.260217472816;5932.614380065282;5533.612647114664;5740.827775166752;5746.88506229873;5764.452377024176;5786.334571524329;5707.74315155819;5772.797952071657;5807.548112971757;5864.47219750216;5889.381614930542;5614.52276556481;5643.35101463337;5659.434056594308;5665.688387138747;5670.688112153871;5154.845354639367;5320.8935821282585;5583.385827558884;5599.580031497735;5603.9545565868975;5851;5884.764609415575;5944.821655350346;5964.224650795369;5984.670843103671;4513.803841981812;4669.252919532859;4689.366935463805;4754.928676069792;4767.785449654704;5707.689540066668;5717.17101020358;5718;5728.312602487728;5743.10981797822;5546.972265138648;5571.192177134374;5575.553955683617;5599.664020158803;5616.831495055155;5231.581473482036;5478.780848766005;5514.420985796594;5573.067834966165;5611.494965453129;5468.781248750164;5477.917831232455;5504.311961004874;5512.476314750093;5539.196816461671;5553.861153471195;5555;5559.6664200148125;5595.118513965393;5620.381758006698;5251.579873610026;5295.391030031596;5387.299651045339;5406.756695948613;5416.539594134542 03:24:23 INFO - PROCESS | 4982 | |5;yui.html;802.8193656427301;832.7168762620707;888.924441422479;896.919277265046;927.3369540778347;16932.15339233038;18349.633007339846;18361.357352492665;18375.173117209742;18376.44870653881;15062.39750409985;16483.67032659346;16649;16662.083585402797;16699.582510437245;854.5940678177847;880.4893161966045;883.1698203688544;889.1286539191597;889.8620713789373;878.9604467798964;881.0616693221731;886.4858382138376;889.030956257679;889.4307643108416;925.8518637018078;932.0399988012324;934.1312579301247;934.621478301289;934.9485778282192;924.37604616884;930.7347405989328;932.6083045121031;932.664240873285;933.2953620016877;686.4336922039338;688.2051230828401;688.3220028272148;688.5490004047357;688.6453476459627;16819.83180168195;17040.318387264495;17191.570210744707;17285.22216500248;17383.47849564515;13585.52450664222;15359.692806143927;15655.452059177987;15830.52508424749;16066.5983350416;13810.93094534531;14565.563033109023;14744.262786860614;14884.62788430287;15190.164540950243;4870.488598697121;4871.293662418965;4884.120858245515;4929.926051109236;4983.103041452537;71372.64313678398;73294.63352683256;86337;88033;88422.11577884204;12015.15893887437;12213.145079844504;12224.938875305566;12251;12383.133180677447;19885.900570497055;20103.497412564804;20208.69686954697;20264.392068237976;20398.69401958972;8179.100298967109;8275.296599789086;8330.125336839666;8616.181462761153;8626;4735.289706543977;4774.4509381420885;4783.091212669582;4826.155422800995;4852.8544143675745;3475.3223121491064;3559.715222782171;3569.696575791035;3569.964300356963;3575.1598374382;644.1111266452266;655.5869802024789;658.1378394303478;658.5159907468008;664.1001443044605;665.364576829127;669.5112567825417;674.5649056358621;702.0101656664079;842.128342352587;10234.48827558619;11007.394593297446;11030.22788404804;11058.944705276423;11181.83227251592;683.7811900191891;698.4970821008866;700.656678227675;707.6532499075416;873.2664561768019;5138.743062846843;5523.972380138073;5572.972135139298;5582.106862901916;5604.85987850307;76441.23558764342;78486.6075669618;78822.21177788264;79399.60300198462;79939.60030199811;71715;72234;72280.63859680668;73417.6329118351;74203.62898185475;64952.675236623516;65029.67485162638;65964.34035659581;66379;68473.65763171153;10618.946905265424;10662.360258384522;10865.130789536975;11002.779944401227;11009;40840.3873941897;41749;41810.37284440797;42066;42776.35835462533;50429.24356134734;50783;51038;51237.48762512327;51238.74380628073;12967.675808104872;12979.221246725227;13015.544455943997;13028.739425211632;13109.934450327688;13620.31898405056;13649.317534123453;13829.170249785046;13835.86164138386;13935.163890166623;13712.588622341345;13890.097143685629;13927.58217253484;14416.855831441551;14439.855601444271;14103.224322661941;14109.735777196036;14211.431542738588;14423.49517766873;14550.199739014455;12672.366381681064;12750.298733569743;12826.61520154397;13173.209607423585;13216.669583260495;9796.118349348593;10009.349392289478;10130;10279.588816447258;10642.8403573948;9679.854802177832;10106.797864042825;10741.301815382289;10844.240903136704;10903.454827258447 03:24:23 INFO - PROCESS | 4982 | __end_tp_report 03:24:23 INFO - PROCESS | 4982 | __start_cc_report 03:24:23 INFO - PROCESS | 4982 | _x_x_mozilla_cycle_collect,1310 03:24:23 INFO - PROCESS | 4982 | __end_cc_report 03:24:23 INFO - PROCESS | 4982 | __startTimestamp1492511063306__endTimestamp 03:24:23 INFO - PROCESS | 4982 | 03:24:23 INFO - PROCESS | 4982 | ------- Summary: start ------- 03:24:23 INFO - PROCESS | 4982 | Number of tests: 6 03:24:23 INFO - PROCESS | 4982 | 03:24:23 INFO - PROCESS | 4982 | [#0] dojo.html Cycles:165 Average:5787.61 Median:6036.03 stddev:656.36 (10.9%) stddev-sans-first:631.16 03:24:23 INFO - PROCESS | 4982 | Values: 3403.4 3437.1 3448.1 3466.5 3495.9 5796.2 5847.3 5864.5 5864.6 5876.4 5777.1 5861.5 5867.8 5875.1 5883.6 5303.4 5322.8 5322.8 5356.1 5421.3 5169.5 5183.8 5223.3 5369.0 5376.2 5332.4 5354.4 5355.0 5372.7 5382.4 5342.8 5361.9 5365.9 5370.7 5393.2 5076.9 5105.0 5262.4 5330.2 5387.4 5649.4 5682.5 5778.1 5829.6 5856.5 6195.5 6233.7 6243.6 6251.1 6252.7 6036.0 6278.8 6293.4 6306.6 6310.2 5746.6 5751.6 5796.6 5798.7 5802.3 6330.1 6366.2 6366.3 6372.5 6373.5 6062.4 6135.1 6140.0 6140.8 6142.2 6026.5 6065.1 6113.7 6118.5 6129.1 6201.7 6243.3 6252.2 6252.2 6255.2 6003.2 6041.5 6050.5 6119.2 6122.5 4693.7 4746.3 4755.1 4762.3 4764.8 4135.2 4137.2 4139.4 4180.4 4263.6 6081.0 6087.6 6090.1 6105.4 6127.4 6230.4 6242.4 6261.7 6270.5 6284.0 5848.9 5863.5 5976.2 6085.9 6094.5 5303.8 5305.7 5325.4 5332.2 5339.5 6543.1 6548.3 6575.7 6577.2 6583.3 5978.5 6182.5 6222.2 6249.8 6271.7 6361.4 6481.3 6514.5 6525.8 6555.7 6149.9 6151.5 6161.6 6165.2 6170.3 6210.5 6266.3 6270.0 6272.1 6273.5 5875.5 5930.1 5970.2 5975.3 6095.8 6074.5 6077.4 6081.0 6083.1 6115.8 5835.1 5859.5 6039.3 6052.3 6055.8 5979.9 6041.6 6107.7 6139.7 6169.1 6028.9 6029.7 6030.2 6033.2 6055.7 03:24:23 INFO - PROCESS | 4982 | 03:24:23 INFO - PROCESS | 4982 | [#1] ext.html Cycles:180 Average:43442.99 Median:32282.10 stddev:68033.00 (210.7%) stddev-sans-first:68148.41 03:24:23 INFO - PROCESS | 4982 | Values: 771.8 823.2 843.4 881.4 884.2 30355.8 41914.4 42469.6 42566.4 42619.6 43899.6 44061.6 44068.6 44101.6 44109.3 7637.2 9146.7 9283.0 9289.6 9292.7 7457.7 10463.7 10664.0 10666.6 10673.9 10877.3 10887.2 10895.0 10901.1 10903.1 10784.6 10812.1 10853.5 10859.8 10870.1 10586.8 10592.6 10608.5 10625.8 10643.7 41179.2 41189.2 41248.6 41257.6 41262.8 4208.7 4212.7 4269.6 4338.6 4372.2 28682.3 29113.7 29132.4 29571.7 30291.5 4276.7 4367.2 4369.8 4373.5 4448.0 217583.9 217926.0 218045.0 218129.0 218761.0 53359.5 53386.5 53441.0 53472.5 53560.0 54567.2 55143.2 55148.7 55211.2 55247.0 15829.4 16338.3 16370.3 16373.3 16384.0 12749.8 12863.7 14534.8 14536.6 14554.2 12657.7 13028.5 13230.4 13262.4 13365.3 5351.8 5383.9 5387.4 5422.8 5457.1 968.7 982.2 988.3 988.7 1005.7 27888.4 27965.6 28181.9 28503.9 28640.6 1029.1 1060.7 1068.9 1069.5 1075.3 14169.8 15206.3 15229.6 15409.2 15449.5 285182.0 287314.0 288599.0 289639.0 290325.0 54358.5 54512.5 54543.0 54753.5 54768.5 1452.4 1457.8 1481.1 1514.4 1528.6 25189.4 26649.0 27120.5 27162.6 27229.3 41644.0 41702.6 41712.6 41791.6 41798.0 271876.0 272947.0 273256.0 274442.0 274485.0 29847.7 31473.8 34516.8 34519.8 34670.5 43571.3 43611.1 43685.0 45052.3 45201.5 33545.7 34003.7 34081.5 34145.5 34289.5 33404.3 33468.5 33599.7 33614.3 33805.7 38350.2 38370.6 38774.2 39113.6 39759.4 31853.8 32247.8 32256.7 32307.5 32452.2 25590.7 25983.7 26050.5 26076.9 26249.3 03:24:23 INFO - PROCESS | 4982 | 03:24:23 INFO - PROCESS | 4982 | [#2] jquery.html Cycles:180 Average:35504.47 Median:22161.89 stddev:98558.07 (444.7%) stddev-sans-first:98799.33 03:24:23 INFO - PROCESS | 4982 | Values: 414.8 468.9 484.3 488.0 492.9 17891.4 19145.5 19792.4 20423.4 20607.7 15277.5 21827.6 22496.2 22522.0 23028.0 6829.3 6926.5 6948.8 7078.3 7109.5 6873.1 6970.4 6978.9 6984.2 6993.4 6860.3 6924.0 6925.7 6979.1 7034.6 6827.2 6972.4 7030.2 7081.9 7082.6 6874.3 6875.0 6879.3 6880.9 6883.0 25792.6 25922.4 26412.9 26519.1 26843.0 3043.0 3052.2 3076.2 3110.8 3132.9 16382.5 16399.8 16771.9 17101.8 17345.0 2272.1 2355.1 2357.8 2358.0 2392.2 84303.2 85024.0 85420.0 90227.0 101459.0 19030.3 20046.4 20284.6 20451.4 20501.6 37740.0 38060.8 38228.2 38228.8 38326.6 9454.4 10450.0 10972.6 11309.7 11539.4 9431.7 9556.3 9563.0 9781.3 9794.2 10358.8 10536.4 10643.6 10705.7 10916.5 3697.3 3926.6 3945.9 4050.9 4174.4 942.6 945.7 948.6 956.5 958.1 19739.0 20740.5 21360.6 21695.1 21709.1 1070.8 1083.8 1088.5 1108.8 1123.2 11428.1 11618.0 11739.9 11747.9 11859.5 449815.0 454620.0 677155.0 678834.0 678834.0 90441.0 100253.5 117093.0 125752.4 127273.0 1333.5 1343.6 1357.8 1384.0 1384.9 18836.5 19808.9 19859.7 19885.8 19892.6 51973.7 52086.2 52720.0 53868.5 54529.7 71528.3 72433.0 72849.6 73028.6 73853.0 12602.1 12721.4 12733.7 12812.2 12852.5 12871.5 14343.7 14440.4 14653.3 14716.0 12234.6 12297.0 12330.6 12383.9 12526.2 11801.7 12059.9 12060.6 12069.1 12361.8 12709.4 12837.6 12847.7 12863.5 12984.1 12527.3 12769.3 12833.6 13047.5 13103.2 12580.4 12613.9 12635.6 12698.9 13042.1 03:24:23 INFO - PROCESS | 4982 | 03:24:23 INFO - PROCESS | 4982 | [#3] mootools.html Cycles:180 Average:7173.57 Median:2444.30 stddev:4677.23 (191.4%) stddev-sans-first:4671.27 03:24:23 INFO - PROCESS | 4982 | Values: 1549.9 1555.9 1561.8 1571.6 1575.5 11027.9 11634.2 11642.7 11664.0 11667.4 11084.9 11334.7 11618.4 11663.4 11685.9 2427.9 2469.5 2471.5 2473.4 2474.9 2391.1 2409.2 2452.9 2453.4 2453.9 2386.4 2455.9 2458.9 2477.6 2480.7 2353.0 2411.9 2437.2 2441.8 2446.8 2409.8 2426.6 2434.4 2437.4 2437.8 11461.3 11664.2 11671.5 11737.8 11759.2 5509.3 5598.1 5615.1 5665.5 5669.5 3483.6 3611.0 3625.2 3636.5 3714.2 1389.3 1420.8 1440.8 1467.7 1474.8 17205.7 17477.7 17993.8 17998.7 18147.4 4803.7 4820.1 4823.9 4871.3 4936.0 13322.3 13349.7 13384.5 13392.9 13401.3 6053.0 6056.8 6059.8 6060.5 6061.5 5275.4 5565.3 5621.4 5622.5 5626.9 4115.3 4152.2 4160.1 4204.6 4207.5 1041.6 1042.9 1046.6 1050.1 1060.7 949.0 970.1 973.3 979.2 988.1 6377.3 6433.2 6526.2 6570.3 6615.8 1006.6 1010.2 1024.7 1048.0 1057.1 4036.8 4216.7 4258.7 4320.7 4393.6 14448.3 15019.1 15034.4 15064.9 15071.2 12528.9 14069.4 14549.1 14975.9 15158.6 9481.5 10461.3 10554.8 10603.7 10679.7 7057.2 7116.8 7128.5 7165.1 7227.9 12993.3 13454.7 13588.1 13600.1 13711.3 12670.0 13879.4 14050.5 14051.2 14083.1 9877.3 10136.3 10172.8 10186.0 10187.9 10926.5 10972.6 11051.6 11053.6 11053.9 9207.4 9292.4 9364.3 9390.9 9464.7 9158.9 9169.4 9212.7 9313.8 9358.4 8229.6 8253.8 8315.7 8346.5 8416.5 5505.4 5677.3 5713.7 5725.2 6081.5 5862.5 5901.3 5957.9 5959.5 6069.7 03:24:23 INFO - PROCESS | 4982 | 03:24:23 INFO - PROCESS | 4982 | [#4] prototype.html Cycles:180 Average:4829.46 Median:5312.83 stddev:1280.35 (24.1%) stddev-sans-first:1240.18 03:24:23 INFO - PROCESS | 4982 | Values: 407.7 421.2 431.7 435.7 440.1 5292.2 5308.7 5323.7 5327.8 5336.4 5218.4 5248.0 5256.7 5259.4 5317.0 4307.9 4360.9 4423.8 4424.5 4450.4 4427.2 4445.6 4447.3 4454.2 4479.6 4453.9 4477.3 4496.5 4496.6 4499.5 4393.1 4434.6 4439.6 4463.1 4471.0 4126.9 4136.2 4148.8 4150.8 4159.6 5020.3 5082.6 5132.7 5138.9 5159.9 4906.6 5103.0 5137.3 5154.2 5167.9 5471.9 5481.0 5496.0 5496.2 5507.8 4537.8 4563.2 4598.1 4622.3 4651.6 5687.3 5706.7 5712.9 5754.9 5771.2 4671.3 4700.4 4700.8 4770.4 4820.0 4828.3 4868.0 4885.6 4894.2 4917.4 4990.2 5056.8 5083.2 5098.4 5158.0 5492.6 5505.9 5506.3 5507.2 5551.4 1632.4 1643.9 1654.9 1658.0 1663.3 1216.0 1265.7 1271.5 1271.7 1308.8 5471.3 5486.6 5499.2 5548.2 5641.6 5455.3 5499.9 5520.4 5604.9 5659.4 5172.2 5441.4 5485.9 5511.9 5522.5 2978.8 2992.8 2997.0 2997.2 3025.6 5666.5 5883.5 5907.5 5918.3 5932.6 5533.6 5740.8 5746.9 5764.5 5786.3 5707.7 5772.8 5807.5 5864.5 5889.4 5614.5 5643.4 5659.4 5665.7 5670.7 5154.8 5320.9 5583.4 5599.6 5604.0 5851.0 5884.8 5944.8 5964.2 5984.7 4513.8 4669.3 4689.4 4754.9 4767.8 5707.7 5717.2 5718.0 5728.3 5743.1 5547.0 5571.2 5575.6 5599.7 5616.8 5231.6 5478.8 5514.4 5573.1 5611.5 5468.8 5477.9 5504.3 5512.5 5539.2 5553.9 5555.0 5559.7 5595.1 5620.4 5251.6 5295.4 5387.3 5406.8 5416.5 03:24:23 INFO - PROCESS | 4982 | 03:24:23 INFO - PROCESS | 4982 | [#5] yui.html Cycles:180 Average:18071.20 Median:4804.62 stddev:22783.58 (474.2%) stddev-sans-first:22810.59 03:24:23 INFO - PROCESS | 4982 | Values: 802.8 832.7 888.9 896.9 927.3 16932.2 18349.6 18361.4 18375.2 18376.4 15062.4 16483.7 16649.0 16662.1 16699.6 854.6 880.5 883.2 889.1 889.9 879.0 881.1 886.5 889.0 889.4 925.9 932.0 934.1 934.6 934.9 924.4 930.7 932.6 932.7 933.3 686.4 688.2 688.3 688.5 688.6 16819.8 17040.3 17191.6 17285.2 17383.5 13585.5 15359.7 15655.5 15830.5 16066.6 13810.9 14565.6 14744.3 14884.6 15190.2 4870.5 4871.3 4884.1 4929.9 4983.1 71372.6 73294.6 86337.0 88033.0 88422.1 12015.2 12213.1 12224.9 12251.0 12383.1 19885.9 20103.5 20208.7 20264.4 20398.7 8179.1 8275.3 8330.1 8616.2 8626.0 4735.3 4774.5 4783.1 4826.2 4852.9 3475.3 3559.7 3569.7 3570.0 3575.2 644.1 655.6 658.1 658.5 664.1 665.4 669.5 674.6 702.0 842.1 10234.5 11007.4 11030.2 11058.9 11181.8 683.8 698.5 700.7 707.7 873.3 5138.7 5524.0 5573.0 5582.1 5604.9 76441.2 78486.6 78822.2 79399.6 79939.6 71715.0 72234.0 72280.6 73417.6 74203.6 64952.7 65029.7 65964.3 66379.0 68473.7 10618.9 10662.4 10865.1 11002.8 11009.0 40840.4 41749.0 41810.4 42066.0 42776.4 50429.2 50783.0 51038.0 51237.5 51238.7 12967.7 12979.2 13015.5 13028.7 13109.9 13620.3 13649.3 13829.2 13835.9 13935.2 13712.6 13890.1 13927.6 14416.9 14439.9 14103.2 14109.7 14211.4 14423.5 14550.2 12672.4 12750.3 12826.6 13173.2 13216.7 9796.1 10009.3 10130.0 10279.6 10642.8 9679.9 10106.8 10741.3 10844.2 10903.5 03:24:23 INFO - PROCESS | 4982 | -------- Summary: end -------- 03:24:23 INFO - PROCESS | 4982 | 03:24:24 INFO - TEST-INFO | 4982: exit 0 03:24:24 INFO - TEST-OK | dromaeo_css | took 1114239ms 03:24:24 INFO - TEST-START | kraken 03:24:24 INFO - operating with platform_type : linux_ 03:24:24 INFO - Initialising browser for kraken test... 03:24:24 INFO - TEST-INFO | started process 5307 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGb0gLw/profile http://localhost:36214/getInfo.html) 03:24:27 INFO - PROCESS | 5307 | __metrics Screen width/height:1600/1200 03:24:27 INFO - PROCESS | 5307 | colorDepth:24 03:24:27 INFO - PROCESS | 5307 | Browser inner width/height: 1024/697 03:24:27 INFO - PROCESS | 5307 | __metrics 03:24:28 INFO - PROCESS | 5307 | 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" 03:24:28 INFO - PROCESS | 5307 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:24:29 INFO - TEST-INFO | 5307: exit 0 03:24:29 INFO - Browser initialized. 03:24:29 INFO - Running cycle 1/1 for kraken test... 03:24:29 INFO - TEST-INFO | started process 5399 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGb0gLw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:24:41 INFO - PROCESS | 5399 | RSS: Main: 139952128 03:24:41 INFO - PROCESS | 5399 | 03:24:44 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36214/tests/kraken/driver.html?testName=audio-beat-detection) 03:24:44 INFO - PROCESS | 5399 | RSS: Main: 183029760 03:24:44 INFO - PROCESS | 5399 | 03:24:47 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36214/tests/kraken/driver.html?testName=audio-dft) 03:24:48 INFO - PROCESS | 5399 | RSS: Main: 173735936 03:24:48 INFO - PROCESS | 5399 | 03:24:50 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36214/tests/kraken/driver.html?testName=audio-fft) 03:24:51 INFO - PROCESS | 5399 | RSS: Main: 265572352 03:24:51 INFO - PROCESS | 5399 | 03:24:53 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36214/tests/kraken/driver.html?testName=audio-oscillator) 03:24:54 INFO - PROCESS | 5399 | RSS: Main: 171216896 03:24:54 INFO - PROCESS | 5399 | 03:24:56 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36214/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:24:56 INFO - PROCESS | 5399 | RSS: Main: 174178304 03:24:56 INFO - PROCESS | 5399 | 03:24:59 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36214/tests/kraken/driver.html?testName=imaging-darkroom) 03:25:00 INFO - PROCESS | 5399 | RSS: Main: 212733952 03:25:00 INFO - PROCESS | 5399 | 03:25:03 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36214/tests/kraken/driver.html?testName=imaging-desaturate) 03:25:04 INFO - PROCESS | 5399 | RSS: Main: 212041728 03:25:04 INFO - PROCESS | 5399 | 03:25:07 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36214/tests/kraken/driver.html?testName=json-parse-financial) 03:25:07 INFO - PROCESS | 5399 | RSS: Main: 210329600 03:25:07 INFO - PROCESS | 5399 | 03:25:09 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36214/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:25:09 INFO - PROCESS | 5399 | RSS: Main: 172777472 03:25:09 INFO - PROCESS | 5399 | 03:25:11 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36214/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:25:11 INFO - PROCESS | 5399 | RSS: Main: 179580928 03:25:11 INFO - PROCESS | 5399 | 03:25:13 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36214/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:25:14 INFO - PROCESS | 5399 | RSS: Main: 189026304 03:25:14 INFO - PROCESS | 5399 | 03:25:17 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:25:17 INFO - PROCESS | 5399 | RSS: Main: 198017024 03:25:17 INFO - PROCESS | 5399 | 03:25:20 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:25:21 INFO - PROCESS | 5399 | RSS: Main: 194732032 03:25:21 INFO - PROCESS | 5399 | 03:25:22 INFO - PROCESS | 5399 | Cycle 1(1): loaded http://localhost:36214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36214/tests/kraken/driver.html?testName=ai-astar) 03:25:23 INFO - PROCESS | 5399 | RSS: Main: 246169600 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | __start_tp_report 03:25:23 INFO - PROCESS | 5399 | _x_x_mozilla_page_load 03:25:23 INFO - PROCESS | 5399 | _x_x_mozilla_page_load_details 03:25:23 INFO - PROCESS | 5399 | |i|pagename|runs| 03:25:23 INFO - PROCESS | 5399 | |0;ai-astar;103;114;112;112;111;112;112;111;112;111 03:25:23 INFO - PROCESS | 5399 | |1;audio-beat-detection;145;114;116;154;116;117;115;114;133;116 03:25:23 INFO - PROCESS | 5399 | |2;audio-dft;161;161;160;161;160;161;161;161;162;159 03:25:23 INFO - PROCESS | 5399 | |3;audio-fft;76;77;75;106;76;77;75;75;96;75 03:25:23 INFO - PROCESS | 5399 | |4;audio-oscillator;82;81;105;83;82;81;94;82;82;105 03:25:23 INFO - PROCESS | 5399 | |5;imaging-gaussian-blur;113;113;113;113;114;113;113;113;113;113 03:25:23 INFO - PROCESS | 5399 | |6;imaging-darkroom;147;147;147;149;151;147;146;146;147;147 03:25:23 INFO - PROCESS | 5399 | |7;imaging-desaturate;94;93;94;94;94;94;94;94;94;93 03:25:23 INFO - PROCESS | 5399 | |8;json-parse-financial;54;55;54;53;54;54;54;55;58;54 03:25:23 INFO - PROCESS | 5399 | |9;json-stringify-tinderbox;52;52;52;52;51;52;52;52;52;52 03:25:23 INFO - PROCESS | 5399 | |10;stanford-crypto-aes;75;76;75;77;80;80;81;76;80;81 03:25:23 INFO - PROCESS | 5399 | |11;stanford-crypto-ccm;121;121;120;138;127;120;130;117;163;131 03:25:23 INFO - PROCESS | 5399 | |12;stanford-crypto-pbkdf2;163;161;160;162;167;162;160;159;167;164 03:25:23 INFO - PROCESS | 5399 | |13;stanford-crypto-sha256-iterative;68;59;62;61;59;59;60;59;59;59 03:25:23 INFO - PROCESS | 5399 | __end_tp_report 03:25:23 INFO - PROCESS | 5399 | __start_cc_report 03:25:23 INFO - PROCESS | 5399 | _x_x_mozilla_cycle_collect,1680 03:25:23 INFO - PROCESS | 5399 | __end_cc_report 03:25:23 INFO - PROCESS | 5399 | __startTimestamp1492511123062__endTimestamp 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | ------- Summary: start ------- 03:25:23 INFO - PROCESS | 5399 | Number of tests: 14 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.00 stddev:2.94 (2.6%) stddev-sans-first:0.93 03:25:23 INFO - PROCESS | 5399 | Values: 103.0 114.0 112.0 112.0 111.0 112.0 112.0 111.0 112.0 111.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#1] audio-beat-detection Cycles:10 Average:124.00 Median:116.50 stddev:14.70 (12.6%) stddev-sans-first:13.48 03:25:23 INFO - PROCESS | 5399 | Values: 145.0 114.0 116.0 154.0 116.0 117.0 115.0 114.0 133.0 116.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 03:25:23 INFO - PROCESS | 5399 | Values: 161.0 161.0 160.0 161.0 160.0 161.0 161.0 161.0 162.0 159.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.00 stddev:10.93 (14.4%) stddev-sans-first:11.46 03:25:23 INFO - PROCESS | 5399 | Values: 76.0 77.0 75.0 106.0 76.0 77.0 75.0 75.0 96.0 75.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:82.00 stddev:9.89 (12.1%) stddev-sans-first:10.27 03:25:23 INFO - PROCESS | 5399 | Values: 82.0 81.0 105.0 83.0 82.0 81.0 94.0 82.0 82.0 105.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 03:25:23 INFO - PROCESS | 5399 | Values: 113.0 113.0 113.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.00 stddev:1.51 (1.0%) stddev-sans-first:1.59 03:25:23 INFO - PROCESS | 5399 | Values: 147.0 147.0 147.0 149.0 151.0 147.0 146.0 146.0 147.0 147.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 03:25:23 INFO - PROCESS | 5399 | Values: 94.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.00 stddev:1.35 (2.5%) stddev-sans-first:1.42 03:25:23 INFO - PROCESS | 5399 | Values: 54.0 55.0 54.0 53.0 54.0 54.0 54.0 55.0 58.0 54.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#9] json-stringify-tinderbox Cycles:10 Average:51.90 Median:52.00 stddev:0.32 (0.6%) stddev-sans-first:0.33 03:25:23 INFO - PROCESS | 5399 | Values: 52.0 52.0 52.0 52.0 51.0 52.0 52.0 52.0 52.0 52.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:80.00 stddev:2.51 (3.1%) stddev-sans-first:2.40 03:25:23 INFO - PROCESS | 5399 | Values: 75.0 76.0 75.0 77.0 80.0 80.0 81.0 76.0 80.0 81.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:128.50 stddev:13.66 (10.6%) stddev-sans-first:14.20 03:25:23 INFO - PROCESS | 5399 | Values: 121.0 121.0 120.0 138.0 127.0 120.0 130.0 117.0 163.0 131.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.50 Median:162.50 stddev:2.80 (1.7%) stddev-sans-first:2.96 03:25:23 INFO - PROCESS | 5399 | Values: 163.0 161.0 160.0 162.0 167.0 162.0 160.0 159.0 167.0 164.0 03:25:23 INFO - PROCESS | 5399 | 03:25:23 INFO - PROCESS | 5399 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.50 Median:59.50 stddev:2.84 (4.8%) stddev-sans-first:1.12 03:25:23 INFO - PROCESS | 5399 | Values: 68.0 59.0 62.0 61.0 59.0 59.0 60.0 59.0 59.0 59.0 03:25:23 INFO - PROCESS | 5399 | -------- Summary: end -------- 03:25:23 INFO - PROCESS | 5399 | 03:25:24 INFO - TEST-INFO | 5399: exit 0 03:25:24 INFO - TEST-OK | kraken | took 59935ms 03:25:24 INFO - SUITE-END | took 1174s 03:25:24 INFO - Completed test suite (00:19:34) 03:25:24 INFO - javascript benchmark 03:25:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7834.707871259235, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3403.3873902697514, 3437.1407148212948, 3448.051785758916, 3466.4973578831086, 3495.877644282449, 5796.217510636063, 5847.327557330903, 5864.530837532998, 5864.648121112737, 5876.44173803488, 5777.133429985504, 5861.5310775138, 5867.76528938842, 5875.118732190173, 5883.617564858281, 5303.363596368432, 5322.76047577858, 5322.787088516455, 5356.143017117262, 5421.2952316199035, 5169.508896654812, 5183.766730497136, 5223.294855194538, 5369.033573956686, 5376.193570964348, 5332.440093790155, 5354.357477102753, 5354.973225133869, 5372.650777699437, 5382.407935127133, 5342.839714808542, 5361.946380536184, 5365.865853353697, 5370.731463426832, 5393.2449457076045, 5076.949230507685, 5105.0, 5262.368515778131, 5330.200469929503, 5387.4073851876265, 5649.435056494318, 5682.488576028178, 5778.133280007991, 5829.621074630136, 5856.502197313191, 6195.504359651217, 6233.688315584202, 6243.56295059346, 6251.12484252205, 6252.718627661766, 6036.034234522455, 6278.811635650938, 6293.433590976788, 6306.558540902139, 6310.211223597051, 5746.626469279525, 5751.597388182829, 5796.623219490762, 5798.710064496759, 5802.33273173582, 6330.0821380899515, 6366.204224471941, 6366.26787919386, 6372.490200783926, 6373.490120790326, 6062.393760623902, 6135.141080248769, 6140.048292514667, 6140.785072522441, 6142.201513803177, 6026.5178785697035, 6065.120557519135, 6113.663748493786, 6118.4668989547035, 6129.05316799051, 6201.658908760061, 6243.250809902841, 6252.187215661935, 6252.218472690914, 6255.186825712719, 6003.249593800775, 6041.516678665696, 6050.485708714808, 6119.207540218003, 6122.5408094392205, 4693.741844198602, 4746.264329029006, 4755.120302744003, 4762.333273341735, 4764.76176191196, 4135.193637240709, 4137.1518838638, 4139.4204811326435, 4180.352045432963, 4263.59495847894, 6080.969595151996, 6087.604305720114, 6090.147379366892, 6105.358937311518, 6127.356627554132, 6230.376962303733, 6242.43818056368, 6261.655608941461, 6270.498360131179, 6284.025975973731, 5848.941510584925, 5863.501602363847, 5976.163337132805, 6085.878282434415, 6094.451499365078, 5303.840884773463, 5305.65513241638, 5325.440828713007, 5332.200169974457, 5339.546138578186, 6543.149390579194, 6548.3124271950755, 6575.671216439159, 6577.21073471177, 6583.341665833378, 5978.461938425388, 6182.505399568114, 6222.186824892722, 6249.812505624838, 6271.686415679289, 6361.395667411589, 6481.319461456667, 6514.4788416925585, 6525.771597994048, 6555.672216389257, 6149.938500614937, 6151.538634602333, 6161.599496032743, 6165.167702360124, 6170.290416602148, 6210.534209934363, 6266.27937787142, 6270.0, 6272.09054687077, 6273.529485288713, 5875.471207591338, 5930.140129681258, 5970.194023806731, 5975.25309336333, 6095.786647467317, 6074.51403887679, 6077.361877002851, 6081.0, 6083.148359229623, 6115.785947491817, 5835.095560188178, 5859.472647461749, 6039.335673076046, 6052.334243233329, 6055.818325450244, 5979.910301345571, 6041.6375017499095, 6107.6640784757265, 6139.737467782926, 6169.105479705508, 6028.87942241144, 6029.668368239789, 6030.216071910623, 6033.215681961317, 6055.697215139314], "value": 5743.763449486879, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.7877583664491, 823.1521532821195, 843.4306842881053, 881.4138597832442, 884.2086332732209, 30355.848220758893, 41914.37128443072, 42469.57530424695, 42566.36150457746, 42619.57380426195, 43899.56100438994, 44061.559384406144, 44068.55931440693, 44101.55898441015, 44109.33835992463, 7637.15991240963, 9146.679866204684, 9282.953585232099, 9289.628414863397, 9292.72121836346, 7457.738979135731, 10463.73840653986, 10663.98692124251, 10666.573337066548, 10673.893261067406, 10877.347359158437, 10887.183461240398, 10895.019448249655, 10901.073408760265, 10903.127749779998, 10784.5686172553, 10812.080973117256, 10853.51159197834, 10859.782804343948, 10870.07604353627, 10586.788264234672, 10592.576296948113, 10608.522616482276, 10625.840612390743, 10643.680689579325, 41179.176416471804, 41189.176216475505, 41248.58751412507, 41257.587424125675, 41262.79368603138, 4208.684348673831, 4212.662986961036, 4269.6370808481315, 4338.587834155782, 4372.2130016597, 28682.282942926384, 29113.708862911313, 29132.41735165306, 29571.70428295711, 30291.545626815616, 4276.657867370603, 4367.192069467127, 4369.781510924441, 4373.540778218273, 4447.954730638297, 217583.91208044018, 217926.0, 218045.0, 218129.0, 218761.0, 53359.46640533623, 53386.466135338924, 53441.0, 53472.465275347524, 53560.0, 54567.18149227765, 55143.172852407246, 55148.72425637926, 55211.171832422544, 55247.0, 15829.445969391016, 16338.346466141458, 16370.345186192419, 16373.263203155648, 16384.0, 12749.808752868714, 12863.678408039874, 14534.781978270334, 14536.563903082924, 14554.199519026555, 12657.746845063048, 13028.47886084565, 13230.404631791593, 13262.403191856389, 13365.26491043002, 5351.759170837322, 5383.946160538423, 5387.3804512481565, 5422.75597598109, 5457.072297709399, 968.6803354892868, 982.1553464020936, 988.3032462114336, 988.7132731507783, 1005.7385079879137, 27888.442231155263, 27965.580516292273, 28181.859090704824, 28503.857480712464, 28640.570391444144, 1029.1098200057047, 1060.7189094889861, 1068.9037986581245, 1069.5187165775433, 1075.2526993739432, 14169.78745318821, 15206.286051324125, 15229.6192595186, 15409.152496612569, 15449.5365139046, 285182.0, 287314.0, 288599.0, 289639.0, 290325.0, 54358.45641543613, 54512.454875451534, 54543.0, 54753.45246547484, 54768.45231547713, 1452.368219824401, 1457.8104846369904, 1481.126135580013, 1514.3942423030876, 1528.6331280492825, 25189.370265743502, 26649.0, 27120.457590848466, 27162.592561111203, 27229.319267018484, 41644.0, 41702.58297416987, 41712.58287417208, 41791.58208417877, 41798.0, 271876.0, 272947.0, 273256.0, 274442.0, 274485.0, 29847.70152298449, 31473.8426307867, 34516.82741586276, 34519.82740086384, 34670.47994280138, 43571.34642980294, 43611.127777444904, 43685.0, 45052.32421513746, 45201.54798452105, 33545.66454335523, 34003.65996340005, 34081.48877766786, 34145.48781768325, 34289.48565771466, 33404.33191336208, 33468.49797252994, 33599.66400336063, 33614.32771344608, 33805.66194338025, 38350.23299533938, 38370.616293836705, 38774.2245155101, 39113.608863910995, 39759.40360894531, 31853.840730796197, 32247.838760806048, 32256.677433225366, 32307.51538726968, 32452.188695281864, 25590.744092558834, 25983.740162598133, 26050.478990420466, 26076.8696156518, 26249.343766405993], "value": 18741.446289472646, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.8340663734504, 468.9484156742758, 484.3316223611418, 488.00690594639906, 492.8767808047988, 17891.373801916936, 19145.52136196596, 19792.406227813153, 20423.387298381036, 20607.690884636744, 15277.465288714897, 21827.563448731056, 22496.212632557865, 22522.0, 23028.0, 6829.3170682931805, 6926.549774264682, 6948.791536253921, 7078.327558881898, 7109.537880037781, 6873.106496155493, 6970.442364610844, 6978.9302106978785, 6984.1619005719385, 6993.370596646301, 6860.279670634562, 6924.030635711005, 6925.653717314114, 6979.09271794666, 7034.613096279702, 6827.180738311435, 6972.442204623618, 7030.156381234259, 7081.89377159343, 7082.64586770662, 6874.2782007889455, 6874.9656251718425, 6879.277675844014, 6880.931190688079, 6883.0, 25792.61311080335, 25922.351941201432, 26412.867935660197, 26519.071832485963, 26843.0, 3043.04144194578, 3052.1759125036115, 3076.215565030903, 3110.8444577771247, 3132.8746850126195, 16382.508524744158, 16399.83600164007, 16771.91614041934, 17101.828981710147, 17345.0, 2272.0684519347155, 2355.057976809273, 2357.811375089989, 2358.0, 2392.234484964812, 84303.15696843015, 85024.0, 85420.0, 90227.0, 101458.99999999926, 19030.33393831209, 20046.39860804178, 20284.59430811405, 20451.386458406272, 20501.58996820055, 37740.0, 38060.809695951895, 38228.235435291135, 38228.8088559561, 38326.61673383286, 9454.385464944757, 10450.0, 10972.561097556008, 11309.71725706864, 11539.365334906493, 9431.717048488556, 9556.331056825955, 9563.0, 9781.266405019653, 9794.167495762798, 10358.844617330746, 10536.420496872746, 10643.627473038407, 10705.732356691145, 10916.454177291103, 3697.3344797936093, 3926.587708290588, 3945.901352466154, 4050.9189816204102, 4174.352975288835, 942.6135284533308, 945.7399215215734, 948.5636607160645, 956.516958935733, 958.0515289862985, 19739.0, 20740.481487962923, 21360.57278854414, 21695.132194712034, 21709.131634734433, 1070.7911957168278, 1083.815751322277, 1088.5047303477058, 1108.8170451875512, 1123.224974767408, 11428.142889283337, 11618.0, 11739.88260117405, 11747.942685158378, 11859.466324015268, 449815.0, 454620.0, 677155.0, 678834.0, 678834.0, 90441.0, 100253.49873250588, 117093.0, 125752.37123814323, 127273.0, 1333.5066025570602, 1343.5700575815645, 1357.83705955286, 1384.0450089438255, 1384.9030567860152, 18836.52908677294, 19808.90095549513, 19859.702104468735, 19885.801141988395, 19892.60214795667, 51973.7401312991, 52086.21870672019, 52720.0, 53868.46131538635, 54529.72735136299, 71528.28471715424, 72433.0, 72849.6357518209, 73028.63485682537, 73853.0, 12602.054845886412, 12721.363931803187, 12733.745325093632, 12812.231266124063, 12852.485900564247, 12871.485140594647, 14343.713125737635, 14440.350184241524, 14653.340599673253, 14716.0, 12234.571789987307, 12297.0, 12330.63008109758, 12383.938080309901, 12526.185797923106, 11801.704957376134, 12059.879401206226, 12060.577879774166, 12069.094817888868, 12361.814572781595, 12709.4280757368, 12837.550685725955, 12847.743045139232, 12863.549775757803, 12984.091113621957, 12527.310997895203, 12769.297688627215, 12833.550825721055, 13047.543335983195, 13103.2138071716, 12580.433880475217, 12613.87386126127, 12635.557755478514, 12698.873011269769, 13042.087053906136], "value": 11712.304137670299, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1549.9457518986837, 1555.9222038898051, 1561.8047744031992, 1571.6149543361873, 1575.5037163293568, 11027.944860275707, 11634.185607007503, 11642.708932276697, 11664.0, 11667.358295293756, 11084.889151108506, 11334.716632084203, 11618.419079046056, 11663.41682915853, 11685.941570292158, 2427.8786060696984, 2469.5307891500647, 2471.468634243637, 2473.369290830836, 2474.8762561871927, 2391.0632387765877, 2409.229046705055, 2452.91414800482, 2453.362125847275, 2453.926382208537, 2386.367612582667, 2455.8526488410753, 2458.8893499792553, 2477.55404027275, 2480.7395223501544, 2352.952940941189, 2411.9397015074587, 2437.2200895713377, 2441.7924476419525, 2446.804255659543, 2409.795167410772, 2426.5632186206476, 2434.415740222352, 2437.439388940536, 2437.8171637127284, 11461.255018423859, 11664.183507154503, 11671.474783634756, 11737.823932641017, 11759.17685761997, 5509.311336082989, 5598.076317407623, 5615.101583746581, 5665.518430933375, 5669.518090962273, 3483.6168021517865, 3611.043073585502, 3625.2024554597947, 3636.5454318210222, 3714.220013797106, 1389.298404305819, 1420.843707192208, 1440.7982882396473, 1467.6697743007844, 1474.787818272591, 17205.655886882192, 17477.650446991116, 17993.820061799477, 17998.730019049595, 18147.364842230458, 4803.735794531264, 4820.060088282751, 4823.855284341475, 4871.269309603588, 4936.0, 13322.333883305795, 13349.733005340033, 13384.464621415229, 13392.866071339356, 13401.262930538915, 6053.0, 6056.757729690763, 6059.818205453844, 6060.545459090586, 6061.545384096122, 5275.393329767049, 5565.276514053147, 5621.353544342372, 5622.522085622686, 5626.915596266059, 4115.300398932189, 4152.231837110145, 4160.1471698301775, 4204.642605378577, 4207.474065741782, 1041.6354276003337, 1042.9061384475433, 1046.5604446132643, 1050.0916707048348, 1060.6658902445859, 948.9810203795886, 970.0832713086066, 973.2846357926918, 979.1628157925014, 988.110700369673, 6377.266614339317, 6433.1636887205295, 6526.2494813096155, 6570.257628776889, 6615.801525954229, 1006.5973610555836, 1010.1767059846202, 1024.6874703215442, 1047.9895201048043, 1057.0592172966067, 4036.8183431745642, 4216.7259128156575, 4258.723182993095, 4320.719153255029, 4393.648508119343, 14448.277586120652, 15019.09885406879, 15034.398624054915, 15064.924675376553, 15071.246437678072, 12528.874711252953, 14069.437222510986, 14549.12705237689, 14975.925120374328, 15158.621034474227, 9481.525923703788, 10461.320014199053, 10554.788904222027, 10603.734906627396, 10679.679609611723, 7057.249011226146, 7116.750913717995, 7128.501004929605, 7165.104361954755, 7227.855442891007, 12993.285369304778, 13454.730905382034, 13588.116772409761, 13600.115992460458, 13711.314434278047, 12670.0, 13879.375428105961, 14050.508232212233, 14051.156930584199, 14083.084599501, 9877.30858839903, 10136.341137826017, 10172.847407289044, 10186.0, 10187.949060254652, 10926.508307126358, 10972.561097556008, 11051.557937682725, 11053.557857685602, 11053.889461105608, 9207.355485115977, 9292.39599426035, 9364.250859931326, 9390.859137112811, 9464.668736594185, 9158.908410915805, 9169.35814492979, 9212.723618291462, 9313.767155821159, 9358.391704539183, 8229.629666665134, 8253.83492330162, 8315.708950186714, 8346.540940248344, 8416.537090460084, 5505.421930697219, 5677.318721753416, 5713.742881570256, 5725.227094342377, 6081.513478921587, 5862.530997520103, 5901.262342207224, 5957.940420595739, 5959.523238141025, 6069.666168360783], "value": 5395.956433246841, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.7361799541174, 421.17449798395137, 431.67192933370666, 435.65365534400166, 440.05388414907964, 5292.2061690746405, 5308.654937429064, 5323.653962492435, 5327.840164795058, 5336.4129945705945, 5218.39988401334, 5248.029114613799, 5256.737163141846, 5259.368875734907, 5317.0, 4307.935380969288, 4360.887973566736, 4423.823047078113, 4424.513303536608, 4450.443694538183, 4427.203103441379, 4445.555444455545, 4447.266201076818, 4454.198244316021, 4479.603412174487, 4453.933191002121, 4477.283634618461, 4496.460424749019, 4496.595306422421, 4499.505054444008, 4393.1433370492605, 4434.625266167498, 4439.64482841372, 4463.072496203353, 4471.0, 4126.938095928564, 4136.172765446892, 4148.771817550033, 4150.83396664133, 4159.629011463467, 5020.297158397827, 5082.593392528625, 5132.743362831843, 5138.897222055575, 5159.948400515985, 4906.582940450066, 5102.974485127587, 5137.280780690707, 5154.201098829687, 5167.948320516822, 5471.890562188773, 5480.958617862593, 5496.032142642968, 5496.1755736639425, 5507.752151153208, 4537.8411755588395, 4563.174730107983, 4598.149342371672, 4622.260438329851, 4651.5813576778255, 5687.260656114679, 5706.714664266811, 5712.857178570527, 5754.884902301972, 5771.249737534135, 4671.346011558385, 4700.365450664185, 4700.824793801549, 4770.356001939694, 4819.975900120477, 4828.299896514985, 4868.02639472107, 4885.60915126789, 4894.241392584155, 4917.385326834145, 4990.226514890198, 5056.82301119459, 5083.237514372874, 5098.388193416739, 5157.994191132693, 5492.642978206404, 5505.862353441196, 5506.311711036121, 5507.201455788968, 5551.3893471718075, 1632.4449687106442, 1643.9342426302815, 1654.9089800060872, 1657.9917100414661, 1663.3180396037808, 1215.9907276960103, 1265.7405231927432, 1271.4742309228961, 1271.6947932496134, 1308.8494823095466, 5471.316085489314, 5486.561075113981, 5499.230107784833, 5548.223248745179, 5641.64600495878, 5455.263539422207, 5499.890002199933, 5520.420355862657, 5604.915926261109, 5659.405762394971, 5172.241437911128, 5441.44918698177, 5485.945140548623, 5511.917321240185, 5522.475364840334, 2978.8233647709467, 2992.787920892042, 2996.9850150749107, 2997.190758495194, 3025.561293612414, 5666.518345940642, 5883.470487656046, 5907.527397808165, 5918.260217472816, 5932.614380065282, 5533.612647114664, 5740.827775166752, 5746.88506229873, 5764.452377024176, 5786.334571524329, 5707.74315155819, 5772.797952071657, 5807.548112971757, 5864.47219750216, 5889.381614930542, 5614.52276556481, 5643.35101463337, 5659.434056594308, 5665.688387138747, 5670.688112153871, 5154.845354639367, 5320.8935821282585, 5583.385827558884, 5599.580031497735, 5603.9545565868975, 5851.0, 5884.764609415575, 5944.821655350346, 5964.224650795369, 5984.670843103671, 4513.803841981812, 4669.252919532859, 4689.366935463805, 4754.928676069792, 4767.785449654704, 5707.689540066668, 5717.17101020358, 5718.0, 5728.312602487728, 5743.10981797822, 5546.972265138648, 5571.192177134374, 5575.553955683617, 5599.664020158803, 5616.831495055155, 5231.581473482036, 5478.780848766005, 5514.420985796594, 5573.067834966165, 5611.494965453129, 5468.781248750164, 5477.917831232455, 5504.311961004874, 5512.476314750093, 5539.196816461671, 5553.861153471195, 5555.0, 5559.6664200148125, 5595.118513965393, 5620.381758006698, 5251.579873610026, 5295.391030031596, 5387.299651045339, 5406.756695948613, 5416.539594134542], "value": 4458.09271493316, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.8193656427301, 832.7168762620707, 888.924441422479, 896.919277265046, 927.3369540778347, 16932.15339233038, 18349.633007339846, 18361.357352492665, 18375.173117209742, 18376.44870653881, 15062.39750409985, 16483.67032659346, 16649.0, 16662.083585402797, 16699.582510437245, 854.5940678177847, 880.4893161966045, 883.1698203688544, 889.1286539191597, 889.8620713789373, 878.9604467798964, 881.0616693221731, 886.4858382138376, 889.030956257679, 889.4307643108416, 925.8518637018078, 932.0399988012324, 934.1312579301247, 934.621478301289, 934.9485778282192, 924.37604616884, 930.7347405989328, 932.6083045121031, 932.664240873285, 933.2953620016877, 686.4336922039338, 688.2051230828401, 688.3220028272148, 688.5490004047357, 688.6453476459627, 16819.83180168195, 17040.318387264495, 17191.570210744707, 17285.22216500248, 17383.47849564515, 13585.52450664222, 15359.692806143927, 15655.452059177987, 15830.52508424749, 16066.5983350416, 13810.93094534531, 14565.563033109023, 14744.262786860614, 14884.62788430287, 15190.164540950243, 4870.488598697121, 4871.293662418965, 4884.120858245515, 4929.926051109236, 4983.103041452537, 71372.64313678398, 73294.63352683256, 86337.0, 88033.0, 88422.11577884204, 12015.15893887437, 12213.145079844504, 12224.938875305566, 12251.0, 12383.133180677447, 19885.900570497055, 20103.497412564804, 20208.69686954697, 20264.392068237976, 20398.69401958972, 8179.100298967109, 8275.296599789086, 8330.125336839666, 8616.181462761153, 8626.0, 4735.289706543977, 4774.4509381420885, 4783.091212669582, 4826.155422800995, 4852.8544143675745, 3475.3223121491064, 3559.715222782171, 3569.696575791035, 3569.964300356963, 3575.1598374382, 644.1111266452266, 655.5869802024789, 658.1378394303478, 658.5159907468008, 664.1001443044605, 665.364576829127, 669.5112567825417, 674.5649056358621, 702.0101656664079, 842.128342352587, 10234.48827558619, 11007.394593297446, 11030.22788404804, 11058.944705276423, 11181.83227251592, 683.7811900191891, 698.4970821008866, 700.656678227675, 707.6532499075416, 873.2664561768019, 5138.743062846843, 5523.972380138073, 5572.972135139298, 5582.106862901916, 5604.85987850307, 76441.23558764342, 78486.6075669618, 78822.21177788264, 79399.60300198462, 79939.60030199811, 71715.0, 72234.0, 72280.63859680668, 73417.6329118351, 74203.62898185475, 64952.675236623516, 65029.67485162638, 65964.34035659581, 66379.0, 68473.65763171153, 10618.946905265424, 10662.360258384522, 10865.130789536975, 11002.779944401227, 11009.0, 40840.3873941897, 41749.0, 41810.37284440797, 42066.0, 42776.35835462533, 50429.24356134734, 50783.0, 51038.0, 51237.48762512327, 51238.74380628073, 12967.675808104872, 12979.221246725227, 13015.544455943997, 13028.739425211632, 13109.934450327688, 13620.31898405056, 13649.317534123453, 13829.170249785046, 13835.86164138386, 13935.163890166623, 13712.588622341345, 13890.097143685629, 13927.58217253484, 14416.855831441551, 14439.855601444271, 14103.224322661941, 14109.735777196036, 14211.431542738588, 14423.49517766873, 14550.199739014455, 12672.366381681064, 12750.298733569743, 12826.61520154397, 13173.209607423585, 13216.669583260495, 9796.118349348593, 10009.349392289478, 10130.0, 10279.588816447258, 10642.8403573948, 9679.854802177832, 10106.797864042825, 10741.301815382289, 10844.240903136704, 10903.454827258447], "value": 7624.9887117900935, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 112.0, 112.0, 111.0, 112.0, 112.0, 111.0, 112.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 114.0, 116.0, 154.0, 116.0, 117.0, 115.0, 114.0, 133.0, 116.0], "value": 124.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 160.0, 161.0, 161.0, 161.0, 162.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 106.0, 76.0, 77.0, 75.0, 75.0, 96.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 105.0, 83.0, 82.0, 81.0, 94.0, 82.0, 82.0, 105.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 149.0, 151.0, 147.0, 146.0, 146.0, 147.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 53.0, 54.0, 54.0, 54.0, 55.0, 58.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 52.0, 51.0, 52.0, 52.0, 52.0, 52.0, 52.0], "value": 51.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 77.0, 80.0, 80.0, 81.0, 76.0, 80.0, 81.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 120.0, 138.0, 127.0, 120.0, 130.0, 117.0, 163.0, 131.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 160.0, 162.0, 167.0, 162.0, 160.0, 159.0, 167.0, 164.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 59.0, 62.0, 61.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0], "value": 60.5, "unit": "score"}]}]} 03:25:24 INFO - Return code: 0 03:25:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:25:24 ERROR - # TBPL SUCCESS # 03:25:24 INFO - Running post-action listener: _resource_record_post_action 03:25:24 INFO - [mozharness: 2017-04-18 10:25:24.394989Z] Finished run-tests step (success) 03:25:24 INFO - Running post-run listener: _resource_record_post_run 03:25:24 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 462848; Write bytes: 373215232; Read time: 88; Write time: 307540 03:25:24 INFO - TinderboxPrint: CPU usage
13.1% 03:25:24 INFO - TinderboxPrint: I/O read bytes / time
462,848 / 88 03:25:24 INFO - TinderboxPrint: I/O write bytes / time
373,215,232 / 307,540 03:25:24 INFO - TinderboxPrint: CPU idle
8,255.8 (86.8%) 03:25:24 INFO - TinderboxPrint: CPU user
1,203.1 (12.7%) 03:25:24 INFO - TinderboxPrint: Swap in / out
0 / 0 03:25:24 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 117989376; Read time: 0; Write time: 125804 03:25:24 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 458752; Write bytes: 254611456; Read time: 76; Write time: 181644 03:25:25 INFO - Running post-run listener: _upload_blobber_files 03:25:25 INFO - Blob upload gear active. 03:25:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:25:25 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 03:25:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:25:25 INFO - (blobuploader) - INFO - Open directory for files ... 03:25:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:25:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:25:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:26 INFO - (blobuploader) - INFO - Done attempting. 03:25:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:25:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:25:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:29 INFO - (blobuploader) - INFO - Done attempting. 03:25:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:25:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:25:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:31 INFO - (blobuploader) - INFO - Done attempting. 03:25:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:25:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:25:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:25:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:25:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:25:32 INFO - (blobuploader) - INFO - Done attempting. 03:25:32 INFO - (blobuploader) - INFO - Iteration through files over. 03:25:32 INFO - Return code: 0 03:25:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:25:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:25:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e626b849ce27409cab69e91b8ee92c1d2714adfce819fde843c29f9e8b7d1f30698126fe79a2296cccd74451d61d8721f5cd66aad0e5cf259d2ef19e0a5714a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/700bdc4b71283aedc0d52b96132e30f4412bbd6b26e4a640f7a35af6d4f9158353ae7e122194aa4bb58ad56f6df1394942bfeb6fdc8bdd39b9f9affd63cbd93c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/420eb9c679275a2decf94f373499178e0417359cff34725b587146ca80986a77f684182956f5e6c663b8c931d6b4691a1dd762a2206b97da8245ef61752ad6d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df1c197eb9bb3ed5d6f3f84861d2848c40c352d1a3d26d218a0fc6df069c64e304180b772b25c25f59e1cb1742dadc5b88c09e9be8ac6540b325668ee6331251"} 03:25:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:25:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:25:32 INFO - Contents: 03:25:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e626b849ce27409cab69e91b8ee92c1d2714adfce819fde843c29f9e8b7d1f30698126fe79a2296cccd74451d61d8721f5cd66aad0e5cf259d2ef19e0a5714a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/700bdc4b71283aedc0d52b96132e30f4412bbd6b26e4a640f7a35af6d4f9158353ae7e122194aa4bb58ad56f6df1394942bfeb6fdc8bdd39b9f9affd63cbd93c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/420eb9c679275a2decf94f373499178e0417359cff34725b587146ca80986a77f684182956f5e6c663b8c931d6b4691a1dd762a2206b97da8245ef61752ad6d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df1c197eb9bb3ed5d6f3f84861d2848c40c352d1a3d26d218a0fc6df069c64e304180b772b25c25f59e1cb1742dadc5b88c09e9be8ac6540b325668ee6331251"} 03:25:32 INFO - Running post-run listener: copy_logs_to_upload_dir 03:25:32 INFO - Copying logs to upload dir... 03:25:32 INFO - mkdir: /builds/slave/test/build/upload/logs 03:25:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.274671 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2017-04-18 03:25:32.085686) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 03:25:32.089528) ========= 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=ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803 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/sha512/6e626b849ce27409cab69e91b8ee92c1d2714adfce819fde843c29f9e8b7d1f30698126fe79a2296cccd74451d61d8721f5cd66aad0e5cf259d2ef19e0a5714a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/700bdc4b71283aedc0d52b96132e30f4412bbd6b26e4a640f7a35af6d4f9158353ae7e122194aa4bb58ad56f6df1394942bfeb6fdc8bdd39b9f9affd63cbd93c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/420eb9c679275a2decf94f373499178e0417359cff34725b587146ca80986a77f684182956f5e6c663b8c931d6b4691a1dd762a2206b97da8245ef61752ad6d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df1c197eb9bb3ed5d6f3f84861d2848c40c352d1a3d26d218a0fc6df069c64e304180b772b25c25f59e1cb1742dadc5b88c09e9be8ac6540b325668ee6331251"} build_url:https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011209 build_url: 'https://queue.taskcluster.net/v1/task/ArcLOxTHSjKaHNlB8lSn2Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e626b849ce27409cab69e91b8ee92c1d2714adfce819fde843c29f9e8b7d1f30698126fe79a2296cccd74451d61d8721f5cd66aad0e5cf259d2ef19e0a5714a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/700bdc4b71283aedc0d52b96132e30f4412bbd6b26e4a640f7a35af6d4f9158353ae7e122194aa4bb58ad56f6df1394942bfeb6fdc8bdd39b9f9affd63cbd93c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/420eb9c679275a2decf94f373499178e0417359cff34725b587146ca80986a77f684182956f5e6c663b8c931d6b4691a1dd762a2206b97da8245ef61752ad6d6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df1c197eb9bb3ed5d6f3f84861d2848c40c352d1a3d26d218a0fc6df069c64e304180b772b25c25f59e1cb1742dadc5b88c09e9be8ac6540b325668ee6331251"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 03:25:32.118958) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 03:25:32.119291) ========= 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=ed14279e38b05397cf4aa391000001a4-1492509878.287237-1469680803 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004445 ========= master_lag: 0.51 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 03:25:32.636016) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 03:25:32.636323) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 03:25:32.637213) ========= ========= Total master_lag: 0.62 =========