builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1491852158.32 results: success (0) revision: 8c1af10581c25ff8948370a4503101efb0c163d2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:38.321725) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:38.322373) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:38.322739) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:38.338791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:38.339215) ========= 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=df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-10 12:22: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2017-04-10 12:22:38 (46.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117272 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:38.471385) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:38.471779) ========= 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=df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015259 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:38.522316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 12:22:38.522694) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8c1af10581c25ff8948370a4503101efb0c163d2 --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 8c1af10581c25ff8948370a4503101efb0c163d2 --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=df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-10 12:22:39,034 truncating revision to first 12 chars 2017-04-10 12:22:39,035 Setting DEBUG logging. 2017-04-10 12:22:39,035 attempt 1/10 2017-04-10 12:22:39,035 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8c1af10581c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-10 12:22:40,331 unpacking tar archive at: mozilla-aurora-8c1af10581c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.704460 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-10 12:22:40.241394) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:40.241786) ========= script_repo_revision: 8c1af10581c25ff8948370a4503101efb0c163d2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:40.242222) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:40.242527) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-10 12:22:40.256350) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-10 12:22:40.256777) ========= /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=df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:22:40 INFO - MultiFileLogger online at 20170410 12:22:40 in /builds/slave/test 12:22: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 12:22:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:22:40 INFO - {'append_to_log': False, 12:22:40 INFO - 'base_work_dir': '/builds/slave/test', 12:22:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:22:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:22:40 INFO - 'branch': 'Mozilla-Aurora', 12:22:40 INFO - 'buildbot_json_path': 'buildprops.json', 12:22:40 INFO - 'config_files': ('talos/linux_config.py',), 12:22:40 INFO - 'default_actions': ('clobber', 12:22:40 INFO - 'read-buildbot-config', 12:22:40 INFO - 'download-and-extract', 12:22:40 INFO - 'populate-webroot', 12:22:40 INFO - 'create-virtualenv', 12:22:40 INFO - 'install', 12:22:40 INFO - 'run-tests'), 12:22:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:22:40 INFO - 'download_minidump_stackwalk': True, 12:22:40 INFO - 'download_symbols': 'ondemand', 12:22:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:22:40 INFO - 'tooltool.py': '/tools/tooltool.py', 12:22:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:22:40 INFO - '/tools/misc-python/virtualenv.py')}, 12:22:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:22:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:22:40 INFO - 'gecko_profile': False, 12:22:40 INFO - 'gecko_profile_interval': 0, 12:22:40 INFO - 'installer_path': 'installer.exe', 12:22:40 INFO - 'log_level': 'info', 12:22:40 INFO - 'log_name': 'talos', 12:22:40 INFO - 'log_to_console': True, 12:22:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:22:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:22:40 INFO - 'opt_config_files': (), 12:22:40 INFO - 'pip_index': False, 12:22:40 INFO - 'suite': 'dromaeojs', 12:22:40 INFO - 'system_bits': '32', 12:22:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:22:40 INFO - 'title': 'talos-linux64-ix-021', 12:22:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:22:40 INFO - 'use_talos_json': True, 12:22:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:22:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:22:40 INFO - 'work_dir': 'build'} 12:22:40 INFO - [mozharness: 2017-04-10 19:22:40.873041Z] Running clobber step. 12:22:40 INFO - Running pre-action listener: _resource_record_pre_action 12:22:40 INFO - Running main action method: clobber 12:22:40 INFO - rmtree: /builds/slave/test/build 12:22:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:22:41 INFO - Running post-action listener: _resource_record_post_action 12:22:41 INFO - [mozharness: 2017-04-10 19:22:41.846376Z] Finished clobber step (success) 12:22:41 INFO - [mozharness: 2017-04-10 19:22:41.846477Z] Running read-buildbot-config step. 12:22:41 INFO - Running pre-action listener: _resource_record_pre_action 12:22:41 INFO - Running main action method: read_buildbot_config 12:22:41 INFO - Using buildbot properties: 12:22:41 INFO - { 12:22:41 INFO - "project": "", 12:22:41 INFO - "product": "firefox", 12:22:41 INFO - "who": "paul@paul.cx", 12:22:41 INFO - "installer_path": "public/build/target.tar.bz2", 12:22:41 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 12:22:41 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 12:22:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 12:22:41 INFO - "stage_platform": "linux64-pgo", 12:22:41 INFO - "basedir": "/builds/slave/test", 12:22:41 INFO - "buildnumber": 879, 12:22:41 INFO - "platform": "ubuntu64_hw", 12:22:41 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:22:41 INFO - "slavebuilddir": "test", 12:22:41 INFO - "taskId": "cYI47OhhRU6Hwsv5a34Lfw", 12:22:41 INFO - "branch": "mozilla-aurora", 12:22:41 INFO - "script_repo_revision": "production", 12:22:41 INFO - "revision": "8c1af10581c25ff8948370a4503101efb0c163d2", 12:22:41 INFO - "slavename": "talos-linux64-ix-021", 12:22:41 INFO - "repo_path": "releases/mozilla-aurora" 12:22:41 INFO - } 12:22:41 INFO - Finding installer, test and symbols from parent task. 12:22:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cYI47OhhRU6Hwsv5a34Lfw'}, attempt #1 12:22:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cYI47OhhRU6Hwsv5a34Lfw'}, attempt #1 12:22:42 INFO - Task dependencies: eesq6nErSJ-ZgL1_ugp3sw 12:22:42 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw'}, attempt #1 12:22:43 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.tar.bz2 12:22:43 INFO - Set test_url: https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/test_packages.json 12:22:43 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.crashreporter-symbols.zip 12:22:43 INFO - Running post-action listener: _resource_record_post_action 12:22:43 INFO - [mozharness: 2017-04-10 19:22:43.249218Z] Finished read-buildbot-config step (success) 12:22:43 INFO - [mozharness: 2017-04-10 19:22:43.249436Z] Running download-and-extract step. 12:22:43 INFO - Running pre-action listener: _resource_record_pre_action 12:22:43 INFO - Running main action method: download_and_extract 12:22:43 INFO - mkdir: /builds/slave/test/build/tests 12:22: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')]} 12:22:43 INFO - trying https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.test_packages.json 12:22:43 INFO - Downloading https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:22:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:22:43 INFO - Downloaded 896 bytes. 12:22:43 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:22:43 INFO - Using the following test package requirements: 12:22:43 INFO - {u'common': [u'target.common.tests.zip'], 12:22:43 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:22:43 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:22:43 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:22:43 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:22:43 INFO - u'mozbase': [u'target.common.tests.zip'], 12:22:43 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:22:43 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:22:43 INFO - u'web-platform': [u'target.common.tests.zip', 12:22:43 INFO - u'target.web-platform.tests.zip'], 12:22:43 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:22:43 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 12:22:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.common.tests.zip 12:22:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:22:43 INFO - Fetch https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.common.tests.zip into memory 12:22:44 INFO - Expected file size: 59542054 12:22:44 INFO - Obtained file size: 59542054 12:22:54 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 12:22:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.common.tests.zip 12:22:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:22:54 INFO - Fetch https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.common.tests.zip into memory 12:22:56 INFO - Expected file size: 59542054 12:22:56 INFO - Obtained file size: 59542054 12:23:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.talos.tests.zip 12:23:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:23:06 INFO - Fetch https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.talos.tests.zip into memory 12:23:07 INFO - Expected file size: 14404424 12:23:07 INFO - Obtained file size: 14404424 12:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:07 INFO - trying https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.tar.bz2 12:23:07 INFO - Downloading https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:23:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:23:08 INFO - Downloaded 66692755 bytes. 12:23:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.tar.bz2 12:23:08 INFO - mkdir: /builds/slave/test/properties 12:23:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:23:08 INFO - Writing to file /builds/slave/test/properties/build_url 12:23:08 INFO - Contents: 12:23:08 INFO - build_url:https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.tar.bz2 12:23:08 INFO - Running post-action listener: _resource_record_post_action 12:23:08 INFO - Running post-action listener: set_extra_try_arguments 12:23:08 INFO - [mozharness: 2017-04-10 19:23:08.913899Z] Finished download-and-extract step (success) 12:23:08 INFO - [mozharness: 2017-04-10 19:23:08.913996Z] Running populate-webroot step. 12:23:08 INFO - Running pre-action listener: _resource_record_pre_action 12:23:08 INFO - Running main action method: populate_webroot 12:23:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:23:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:23:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:23:08 INFO - '--tppagecycles', 12:23:08 INFO - '7'], 12:23:08 INFO - 'tests': ['tsvgm']}}, 12:23:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:23:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:23:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:23:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:23:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:23:08 INFO - 'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:23:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:23:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:23:08 INFO - 'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['damp', 'tps']}, 12:23:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:23:08 INFO - 'tests': ['damp', 'tps']}, 12:23:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['dromaeo_dom']}, 12:23:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:23:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['basic_compositor_video']}, 12:23:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:23:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['a11yr', 12:23:08 INFO - 'ts_paint', 12:23:08 INFO - 'tpaint', 12:23:08 INFO - 'sessionrestore', 12:23:08 INFO - 'sessionrestore_no_auto_restore', 12:23:08 INFO - 'tabpaint']}, 12:23:08 INFO - 'other-e10s': {'tests': ['a11yr', 12:23:08 INFO - 'ts_paint', 12:23:08 INFO - 'tpaint', 12:23:08 INFO - 'sessionrestore', 12:23:08 INFO - 'sessionrestore_no_auto_restore', 12:23:08 INFO - 'tabpaint']}, 12:23:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:23:08 INFO - 'ts_paint', 12:23:08 INFO - 'tpaint', 12:23:08 INFO - 'sessionrestore', 12:23:08 INFO - 'sessionrestore_no_auto_restore', 12:23:08 INFO - 'tabpaint']}, 12:23:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:23:08 INFO - 'ts_paint', 12:23:08 INFO - 'tpaint', 12:23:08 INFO - 'sessionrestore', 12:23:08 INFO - 'sessionrestore_no_auto_restore', 12:23:08 INFO - 'tabpaint']}, 12:23:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['a11yr', 12:23:08 INFO - 'ts_paint', 12:23:08 INFO - 'tpaint', 12:23:08 INFO - 'sessionrestore', 12:23:08 INFO - 'sessionrestore_no_auto_restore', 12:23:08 INFO - 'tabpaint']}, 12:23:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['a11yr', 12:23:08 INFO - 'ts_paint', 12:23:08 INFO - 'tpaint', 12:23:08 INFO - 'sessionrestore', 12:23:08 INFO - 'sessionrestore_no_auto_restore', 12:23:08 INFO - 'tabpaint']}, 12:23:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['tsvgx', 12:23:08 INFO - 'tsvgr_opacity', 12:23:08 INFO - 'tart', 12:23:08 INFO - 'tscrollx', 12:23:08 INFO - 'cart', 12:23:08 INFO - 'tsvg_static']}, 12:23:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:23:08 INFO - 'tsvgr_opacity', 12:23:08 INFO - 'tart', 12:23:08 INFO - 'tscrollx', 12:23:08 INFO - 'cart', 12:23:08 INFO - 'tsvg_static']}, 12:23:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:23:08 INFO - 'talos_options': ['--disable-e10s'], 12:23:08 INFO - 'tests': ['tp5o']}, 12:23:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:23:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:23:08 INFO - 'talos_options': ['--disable-e10s', 12:23:08 INFO - '--xperf_path', 12:23:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:08 INFO - 'tests': ['tp5n']}, 12:23:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:23:08 INFO - 'talos_options': ['--xperf_path', 12:23:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:23:08 INFO - 'tests': ['tp5n']}}, 12:23:08 INFO - 'talos.zip': {'path': '', 12:23:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:23:08 INFO - Running post-action listener: _resource_record_post_action 12:23:08 INFO - [mozharness: 2017-04-10 19:23:08.927217Z] Finished populate-webroot step (success) 12:23:08 INFO - [mozharness: 2017-04-10 19:23:08.927307Z] Running create-virtualenv step. 12:23:08 INFO - Running pre-action listener: _resource_record_pre_action 12:23:08 INFO - Running main action method: create_virtualenv 12:23:08 INFO - Creating virtualenv /builds/slave/test/build/venv 12:23:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:23:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:23:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:23:09 INFO - Using real prefix '/usr' 12:23:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:23:09 INFO - Installing distribute.............................................................................................................................................................................................done. 12:23:11 INFO - Installing pip.................done. 12:23:11 INFO - Return code: 0 12:23:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:23:11 INFO - Reading from file tmpfile_stdout 12:23:11 INFO - Output received: 12:23:11 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:23:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:11 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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:23:11 INFO - Using env: {'DISPLAY': ':0', 12:23:11 INFO - 'HOME': '/home/cltbld', 12:23:11 INFO - 'LANG': 'en_US.UTF-8', 12:23:11 INFO - 'LANGUAGE': 'en_US:en', 12:23:11 INFO - 'LOGNAME': 'cltbld', 12:23:11 INFO - 'MAIL': '/var/mail/cltbld', 12:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:11 INFO - 'MOZ_NO_REMOTE': '1', 12:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:11 INFO - 'NO_EM_RESTART': '1', 12:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:11 INFO - 'PWD': '/builds/slave/test', 12:23:11 INFO - 'SHELL': '/bin/bash', 12:23:11 INFO - 'SHLVL': '1', 12:23:11 INFO - 'TERM': 'linux', 12:23:11 INFO - 'TMOUT': '86400', 12:23:11 INFO - 'USER': 'cltbld', 12:23:11 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:11 INFO - '_': '/tools/buildbot/bin/python'} 12:23:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:11 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:23:11 INFO - Cleaning up... 12:23:11 INFO - Return code: 0 12:23:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:11 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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:23:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:23:11 INFO - Using env: {'DISPLAY': ':0', 12:23:11 INFO - 'HOME': '/home/cltbld', 12:23:11 INFO - 'LANG': 'en_US.UTF-8', 12:23:11 INFO - 'LANGUAGE': 'en_US:en', 12:23:11 INFO - 'LOGNAME': 'cltbld', 12:23:11 INFO - 'MAIL': '/var/mail/cltbld', 12:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:11 INFO - 'MOZ_NO_REMOTE': '1', 12:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:11 INFO - 'NO_EM_RESTART': '1', 12:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:11 INFO - 'PWD': '/builds/slave/test', 12:23:11 INFO - 'SHELL': '/bin/bash', 12:23:11 INFO - 'SHLVL': '1', 12:23:11 INFO - 'TERM': 'linux', 12:23:11 INFO - 'TMOUT': '86400', 12:23:11 INFO - 'USER': 'cltbld', 12:23:11 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:11 INFO - '_': '/tools/buildbot/bin/python'} 12:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:12 INFO - Downloading/unpacking psutil>=3.1.1 12:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:23:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:14 INFO - Installing collected packages: psutil 12:23:14 INFO - Running setup.py install for psutil 12:23:14 INFO - building 'psutil._psutil_linux' extension 12:23:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 12:23:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 12:23:14 INFO - building 'psutil._psutil_posix' extension 12:23:14 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 12:23:14 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 12:23:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:23:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:23:14 INFO - Successfully installed psutil 12:23:14 INFO - Cleaning up... 12:23:14 INFO - Return code: 0 12:23:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:14 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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:23:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:23:14 INFO - Using env: {'DISPLAY': ':0', 12:23:14 INFO - 'HOME': '/home/cltbld', 12:23:14 INFO - 'LANG': 'en_US.UTF-8', 12:23:14 INFO - 'LANGUAGE': 'en_US:en', 12:23:14 INFO - 'LOGNAME': 'cltbld', 12:23:14 INFO - 'MAIL': '/var/mail/cltbld', 12:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:14 INFO - 'MOZ_NO_REMOTE': '1', 12:23:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:14 INFO - 'NO_EM_RESTART': '1', 12:23:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:14 INFO - 'PWD': '/builds/slave/test', 12:23:14 INFO - 'SHELL': '/bin/bash', 12:23:14 INFO - 'SHLVL': '1', 12:23:14 INFO - 'TERM': 'linux', 12:23:14 INFO - 'TMOUT': '86400', 12:23:14 INFO - 'USER': 'cltbld', 12:23:14 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:14 INFO - '_': '/tools/buildbot/bin/python'} 12:23:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:23:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:23:17 INFO - Installing collected packages: mozsystemmonitor 12:23:17 INFO - Running setup.py install for mozsystemmonitor 12:23:17 INFO - Successfully installed mozsystemmonitor 12:23:17 INFO - Cleaning up... 12:23:17 INFO - Return code: 0 12:23:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:17 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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:23:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:23:17 INFO - Using env: {'DISPLAY': ':0', 12:23:17 INFO - 'HOME': '/home/cltbld', 12:23:17 INFO - 'LANG': 'en_US.UTF-8', 12:23:17 INFO - 'LANGUAGE': 'en_US:en', 12:23:17 INFO - 'LOGNAME': 'cltbld', 12:23:17 INFO - 'MAIL': '/var/mail/cltbld', 12:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:17 INFO - 'MOZ_NO_REMOTE': '1', 12:23:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:17 INFO - 'NO_EM_RESTART': '1', 12:23:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:17 INFO - 'PWD': '/builds/slave/test', 12:23:17 INFO - 'SHELL': '/bin/bash', 12:23:17 INFO - 'SHLVL': '1', 12:23:17 INFO - 'TERM': 'linux', 12:23:17 INFO - 'TMOUT': '86400', 12:23:17 INFO - 'USER': 'cltbld', 12:23:17 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:17 INFO - '_': '/tools/buildbot/bin/python'} 12:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:17 INFO - Downloading/unpacking jsonschema==2.5.1 12:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:23:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:19 INFO - Downloading functools32-3.2.3-2.tar.gz 12:23:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:23:19 INFO - warning: no files found matching '*.txt' 12:23:19 INFO - no previously-included directories found matching 'build' 12:23:19 INFO - no previously-included directories found matching 'dist' 12:23:19 INFO - no previously-included directories found matching '.git*' 12:23:19 INFO - Installing collected packages: jsonschema, functools32 12:23:19 INFO - Running setup.py install for functools32 12:23:19 INFO - warning: no files found matching '*.txt' 12:23:19 INFO - no previously-included directories found matching 'build' 12:23:19 INFO - no previously-included directories found matching 'dist' 12:23:19 INFO - no previously-included directories found matching '.git*' 12:23:19 INFO - Successfully installed jsonschema functools32 12:23:19 INFO - Cleaning up... 12:23:19 INFO - Return code: 0 12:23:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:19 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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:23:19 INFO - Using env: {'DISPLAY': ':0', 12:23:19 INFO - 'HOME': '/home/cltbld', 12:23:19 INFO - 'LANG': 'en_US.UTF-8', 12:23:19 INFO - 'LANGUAGE': 'en_US:en', 12:23:19 INFO - 'LOGNAME': 'cltbld', 12:23:19 INFO - 'MAIL': '/var/mail/cltbld', 12:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:19 INFO - 'MOZ_NO_REMOTE': '1', 12:23:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:19 INFO - 'NO_EM_RESTART': '1', 12:23:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:19 INFO - 'PWD': '/builds/slave/test', 12:23:19 INFO - 'SHELL': '/bin/bash', 12:23:19 INFO - 'SHLVL': '1', 12:23:19 INFO - 'TERM': 'linux', 12:23:19 INFO - 'TMOUT': '86400', 12:23:19 INFO - 'USER': 'cltbld', 12:23:19 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:19 INFO - '_': '/tools/buildbot/bin/python'} 12:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:23:20 INFO - Cleaning up... 12:23:20 INFO - Return code: 0 12:23:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:23: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')]} 12:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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')]} 12:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23: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', '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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:23: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 blobuploader==1.2.4 12:23:20 INFO - Using env: {'DISPLAY': ':0', 12:23:20 INFO - 'HOME': '/home/cltbld', 12:23:20 INFO - 'LANG': 'en_US.UTF-8', 12:23:20 INFO - 'LANGUAGE': 'en_US:en', 12:23:20 INFO - 'LOGNAME': 'cltbld', 12:23:20 INFO - 'MAIL': '/var/mail/cltbld', 12:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:20 INFO - 'MOZ_NO_REMOTE': '1', 12:23:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:20 INFO - 'NO_EM_RESTART': '1', 12:23:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:20 INFO - 'PWD': '/builds/slave/test', 12:23:20 INFO - 'SHELL': '/bin/bash', 12:23:20 INFO - 'SHLVL': '1', 12:23:20 INFO - 'TERM': 'linux', 12:23:20 INFO - 'TMOUT': '86400', 12:23:20 INFO - 'USER': 'cltbld', 12:23:20 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:20 INFO - '_': '/tools/buildbot/bin/python'} 12:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:20 INFO - Downloading/unpacking blobuploader==1.2.4 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23: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 12:23:22 INFO - Downloading blobuploader-1.2.4.tar.gz 12:23:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:23:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:23:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:23 INFO - Downloading docopt-0.6.1.tar.gz 12:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:23:23 INFO - Installing collected packages: blobuploader, requests, docopt 12:23:23 INFO - Running setup.py install for blobuploader 12:23:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:23:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:23:23 INFO - Running setup.py install for requests 12:23:23 INFO - Running setup.py install for docopt 12:23:23 INFO - Successfully installed blobuploader requests docopt 12:23:23 INFO - Cleaning up... 12:23:23 INFO - Return code: 0 12:23:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:23 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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:23:23 INFO - Using env: {'DISPLAY': ':0', 12:23:23 INFO - 'HOME': '/home/cltbld', 12:23:23 INFO - 'LANG': 'en_US.UTF-8', 12:23:23 INFO - 'LANGUAGE': 'en_US:en', 12:23:23 INFO - 'LOGNAME': 'cltbld', 12:23:23 INFO - 'MAIL': '/var/mail/cltbld', 12:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:23 INFO - 'MOZ_NO_REMOTE': '1', 12:23:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:23 INFO - 'NO_EM_RESTART': '1', 12:23:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:23 INFO - 'PWD': '/builds/slave/test', 12:23:23 INFO - 'SHELL': '/bin/bash', 12:23:23 INFO - 'SHLVL': '1', 12:23:23 INFO - 'TERM': 'linux', 12:23:23 INFO - 'TMOUT': '86400', 12:23:23 INFO - 'USER': 'cltbld', 12:23:23 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:23 INFO - '_': '/tools/buildbot/bin/python'} 12:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:24 INFO - Running setup.py (path:/tmp/pip-wDCopJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:24 INFO - Running setup.py (path:/tmp/pip-jBa3K7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:24 INFO - Running setup.py (path:/tmp/pip-tjRcYX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:24 INFO - Running setup.py (path:/tmp/pip-Wtp4a_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:24 INFO - Running setup.py (path:/tmp/pip-gzmBtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:24 INFO - Running setup.py (path:/tmp/pip-kgO3hN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:24 INFO - Running setup.py (path:/tmp/pip-Sa02YH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:24 INFO - Running setup.py (path:/tmp/pip-H_JJad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:24 INFO - Running setup.py (path:/tmp/pip-JmBFvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:24 INFO - Running setup.py (path:/tmp/pip-0Rhhwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:24 INFO - Running setup.py (path:/tmp/pip-tQB1tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:24 INFO - Running setup.py (path:/tmp/pip-8Qk1XJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:24 INFO - Running setup.py (path:/tmp/pip-j9hqHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:25 INFO - Running setup.py (path:/tmp/pip-_ufLpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:25 INFO - Running setup.py (path:/tmp/pip-zV2l6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:25 INFO - Running setup.py (path:/tmp/pip-VIg643-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:25 INFO - Running setup.py (path:/tmp/pip-KYGmpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:23:25 INFO - Running setup.py install for manifestparser 12:23:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:23:25 INFO - Running setup.py install for mozcrash 12:23:25 INFO - Running setup.py install for mozdebug 12:23:25 INFO - Running setup.py install for mozdevice 12:23:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:23:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:23:25 INFO - Running setup.py install for mozfile 12:23:25 INFO - Running setup.py install for mozhttpd 12:23:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:23:25 INFO - Running setup.py install for mozinfo 12:23:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:23:25 INFO - Running setup.py install for mozInstall 12:23:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Running setup.py install for mozleak 12:23:26 INFO - Running setup.py install for mozlog 12:23:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Running setup.py install for moznetwork 12:23:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Running setup.py install for mozprocess 12:23:26 INFO - Running setup.py install for mozprofile 12:23:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Running setup.py install for mozrunner 12:23:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:23:26 INFO - Running setup.py install for mozscreenshot 12:23:26 INFO - Running setup.py install for moztest 12:23:26 INFO - Running setup.py install for mozversion 12:23:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:23:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:23:27 INFO - Cleaning up... 12:23:27 INFO - Return code: 0 12:23:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:27 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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:23:27 INFO - Using env: {'DISPLAY': ':0', 12:23:27 INFO - 'HOME': '/home/cltbld', 12:23:27 INFO - 'LANG': 'en_US.UTF-8', 12:23:27 INFO - 'LANGUAGE': 'en_US:en', 12:23:27 INFO - 'LOGNAME': 'cltbld', 12:23:27 INFO - 'MAIL': '/var/mail/cltbld', 12:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:27 INFO - 'MOZ_NO_REMOTE': '1', 12:23:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:27 INFO - 'NO_EM_RESTART': '1', 12:23:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:27 INFO - 'PWD': '/builds/slave/test', 12:23:27 INFO - 'SHELL': '/bin/bash', 12:23:27 INFO - 'SHLVL': '1', 12:23:27 INFO - 'TERM': 'linux', 12:23:27 INFO - 'TMOUT': '86400', 12:23:27 INFO - 'USER': 'cltbld', 12:23:27 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:27 INFO - '_': '/tools/buildbot/bin/python'} 12:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:23:27 INFO - Running setup.py (path:/tmp/pip-HSNV_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:23:27 INFO - Running setup.py (path:/tmp/pip-tzweIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:23:27 INFO - Running setup.py (path:/tmp/pip-P7pqN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:23:27 INFO - Running setup.py (path:/tmp/pip-HvNW1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:23:27 INFO - Running setup.py (path:/tmp/pip-LjYkjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:23:27 INFO - Running setup.py (path:/tmp/pip-td0s3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:23:27 INFO - Running setup.py (path:/tmp/pip-RAO9wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:23:27 INFO - Running setup.py (path:/tmp/pip-DBLjxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:23:27 INFO - Running setup.py (path:/tmp/pip-dyr_BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:23:27 INFO - Running setup.py (path:/tmp/pip-gAeF68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:23:28 INFO - Running setup.py (path:/tmp/pip-F_bWWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:23:28 INFO - Running setup.py (path:/tmp/pip-iUbGI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:23:28 INFO - Running setup.py (path:/tmp/pip-vnaUes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:23:28 INFO - Running setup.py (path:/tmp/pip-lHFrWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:28 INFO - Running setup.py (path:/tmp/pip-92G27M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:23:28 INFO - Running setup.py (path:/tmp/pip-jqkZIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:23:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:23:28 INFO - Running setup.py (path:/tmp/pip-FCYOqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:23:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:23:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:23:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:23:30 INFO - Downloading blessings-1.6.tar.gz 12:23:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:23:30 INFO - Installing collected packages: blessings 12:23:30 INFO - Running setup.py install for blessings 12:23:30 INFO - Successfully installed blessings 12:23:30 INFO - Cleaning up... 12:23:30 INFO - Return code: 0 12:23:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:31 INFO - Reading from file tmpfile_stdout 12:23:31 INFO - Current package versions: 12:23:31 INFO - argparse == 1.2.1 12:23:31 INFO - blessings == 1.6 12:23:31 INFO - blobuploader == 1.2.4 12:23:31 INFO - docopt == 0.6.1 12:23:31 INFO - functools32 == 3.2.3-2 12:23:31 INFO - jsonschema == 2.5.1 12:23:31 INFO - manifestparser == 1.1 12:23:31 INFO - mozInstall == 1.12 12:23:31 INFO - mozcrash == 1.0 12:23:31 INFO - mozdebug == 0.1 12:23:31 INFO - mozdevice == 0.49 12:23:31 INFO - mozfile == 1.2 12:23:31 INFO - mozhttpd == 0.7 12:23:31 INFO - mozinfo == 0.9 12:23:31 INFO - mozleak == 0.1 12:23:31 INFO - mozlog == 3.4 12:23:31 INFO - moznetwork == 0.27 12:23:31 INFO - mozprocess == 0.25 12:23:31 INFO - mozprofile == 0.28 12:23:31 INFO - mozrunner == 6.13 12:23:31 INFO - mozscreenshot == 0.1 12:23:31 INFO - mozsystemmonitor == 0.3 12:23:31 INFO - moztest == 0.8 12:23:31 INFO - mozversion == 1.4 12:23:31 INFO - psutil == 3.1.1 12:23:31 INFO - requests == 1.2.3 12:23:31 INFO - wsgiref == 0.1.2 12:23:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:31 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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:23:31 INFO - Using env: {'DISPLAY': ':0', 12:23:31 INFO - 'HOME': '/home/cltbld', 12:23:31 INFO - 'LANG': 'en_US.UTF-8', 12:23:31 INFO - 'LANGUAGE': 'en_US:en', 12:23:31 INFO - 'LOGNAME': 'cltbld', 12:23:31 INFO - 'MAIL': '/var/mail/cltbld', 12:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:31 INFO - 'MOZ_NO_REMOTE': '1', 12:23:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:31 INFO - 'NO_EM_RESTART': '1', 12:23:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:31 INFO - 'PWD': '/builds/slave/test', 12:23:31 INFO - 'SHELL': '/bin/bash', 12:23:31 INFO - 'SHLVL': '1', 12:23:31 INFO - 'TERM': 'linux', 12:23:31 INFO - 'TMOUT': '86400', 12:23:31 INFO - 'USER': 'cltbld', 12:23:31 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:31 INFO - '_': '/tools/buildbot/bin/python'} 12:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:23:31 INFO - Cleaning up... 12:23:31 INFO - Return code: 0 12:23:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:23:31 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 0x28941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x298ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b31b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, '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': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 12:23:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:23:31 INFO - Using env: {'DISPLAY': ':0', 12:23:31 INFO - 'HOME': '/home/cltbld', 12:23:31 INFO - 'LANG': 'en_US.UTF-8', 12:23:31 INFO - 'LANGUAGE': 'en_US:en', 12:23:31 INFO - 'LOGNAME': 'cltbld', 12:23:31 INFO - 'MAIL': '/var/mail/cltbld', 12:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:31 INFO - 'MOZ_NO_REMOTE': '1', 12:23:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:31 INFO - 'NO_EM_RESTART': '1', 12:23:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:31 INFO - 'PWD': '/builds/slave/test', 12:23:31 INFO - 'SHELL': '/bin/bash', 12:23:31 INFO - 'SHLVL': '1', 12:23:31 INFO - 'TERM': 'linux', 12:23:31 INFO - 'TMOUT': '86400', 12:23:31 INFO - 'USER': 'cltbld', 12:23:31 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:31 INFO - '_': '/tools/buildbot/bin/python'} 12:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:23:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:23:31 INFO - Cleaning up... 12:23:31 INFO - Return code: 0 12:23:31 INFO - Running post-action listener: _resource_record_post_action 12:23:31 INFO - Running post-action listener: _start_resource_monitoring 12:23:31 INFO - Starting resource monitoring. 12:23:31 INFO - [mozharness: 2017-04-10 19:23:31.551681Z] Finished create-virtualenv step (success) 12:23:31 INFO - [mozharness: 2017-04-10 19:23:31.552144Z] Running install step. 12:23:31 INFO - Running pre-action listener: _resource_record_pre_action 12:23:31 INFO - Running main action method: install 12:23:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:23:31 INFO - Reading from file tmpfile_stdout 12:23:31 INFO - Detecting whether we're running mozinstall >=1.0... 12:23:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:23:31 INFO - Reading from file tmpfile_stdout 12:23:31 INFO - Output received: 12:23:31 INFO - Usage: mozinstall [options] installer 12:23:31 INFO - Options: 12:23:31 INFO - -h, --help show this help message and exit 12:23:31 INFO - -d DEST, --destination=DEST 12:23:31 INFO - Directory to install application into. [default: 12:23:31 INFO - "/builds/slave/test"] 12:23:31 INFO - --app=APP Application being installed. [default: firefox] 12:23:31 INFO - mkdir: /builds/slave/test/build/application 12:23:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:23:48 INFO - Reading from file tmpfile_stdout 12:23:48 INFO - Output received: 12:23:48 INFO - /builds/slave/test/build/application/firefox/firefox 12:23:48 INFO - Running post-action listener: _resource_record_post_action 12:23:48 INFO - [mozharness: 2017-04-10 19:23:48.435202Z] Finished install step (success) 12:23:48 INFO - [mozharness: 2017-04-10 19:23:48.435478Z] Running run-tests step. 12:23:48 INFO - Running pre-action listener: _resource_record_pre_action 12:23:48 INFO - Running main action method: run_tests 12:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:48 INFO - Python 2.7.3 12:23:48 INFO - Return code: 0 12:23:48 INFO - grabbing minidump binary from tooltool 12:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:23:48 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 0x2b2f290>, '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 0x2b2fb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b30040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:48 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:23:48 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 12:23:48 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:23:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:48 INFO - Return code: 0 12:23:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:23:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:48 INFO - ENV: RUST_BACKTRACE is now 1 12:23:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:23:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:48 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/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:23:48 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/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:23:48 INFO - Using env: {'DISPLAY': ':0', 12:23:48 INFO - 'HOME': '/home/cltbld', 12:23:48 INFO - 'LANG': 'en_US.UTF-8', 12:23:48 INFO - 'LANGUAGE': 'en_US:en', 12:23:48 INFO - 'LOGNAME': 'cltbld', 12:23:48 INFO - 'MAIL': '/var/mail/cltbld', 12:23:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:48 INFO - 'MOZ_NO_REMOTE': '1', 12:23:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:23:48 INFO - 'NO_EM_RESTART': '1', 12:23:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:48 INFO - 'PWD': '/builds/slave/test', 12:23:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:48 INFO - 'RUST_BACKTRACE': '1', 12:23:48 INFO - 'SHELL': '/bin/bash', 12:23:48 INFO - 'SHLVL': '1', 12:23:48 INFO - 'TERM': 'linux', 12:23:48 INFO - 'TMOUT': '86400', 12:23:48 INFO - 'USER': 'cltbld', 12:23:48 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649', 12:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:48 INFO - '_': '/tools/buildbot/bin/python'} 12:23:48 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/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:23:48 INFO - mozversion application_buildid: 20170410181642 12:23:48 INFO - mozversion application_changeset: 8c1af10581c25ff8948370a4503101efb0c163d2 12:23:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:23:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:48 INFO - mozversion application_name: Firefox 12:23:48 INFO - mozversion application_remotingname: firefox-dev 12:23:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:23:48 INFO - mozversion application_vendor: Mozilla 12:23:48 INFO - mozversion application_version: 54.0a2 12:23:48 INFO - mozversion platform_buildid: 20170410181642 12:23:48 INFO - mozversion platform_changeset: 8c1af10581c25ff8948370a4503101efb0c163d2 12:23:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:23:48 INFO - mozversion platform_version: 54.0a2 12:23:48 INFO - using testdate: 1491852228 12:23:48 INFO - actual date: 1491852228 12:23:48 INFO - starting webserver on 'localhost:52101' 12:23:48 INFO - SUITE-START | Running 2 tests 12:23:48 INFO - TEST-START | dromaeo_css 12:23:48 INFO - operating with platform_type : linux_ 12:23:48 INFO - Initialising browser for dromaeo_css test... 12:23:48 INFO - TEST-INFO | started process 12498 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmA_d5V/profile http://localhost:52101/getInfo.html) 12:23:53 INFO - PROCESS | 12498 | __metrics Screen width/height:1600/1200 12:23:53 INFO - PROCESS | 12498 | colorDepth:24 12:23:53 INFO - PROCESS | 12498 | Browser inner width/height: 1024/697 12:23:53 INFO - PROCESS | 12498 | __metrics 12:23:54 INFO - PROCESS | 12498 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:23:54 INFO - PROCESS | 12498 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:23:54 INFO - PROCESS | 12498 | console.error: PushService: 12:23:54 INFO - PROCESS | 12498 | stateChangeProcessEnqueue: Error transitioning state 12:23:54 INFO - PROCESS | 12498 | UnknownError 12:23:55 INFO - TEST-INFO | 12498: exit 0 12:23:55 INFO - Browser initialized. 12:23:55 INFO - Running cycle 1/1 for dromaeo_css test... 12:23:55 INFO - TEST-INFO | started process 12593 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmA_d5V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:24:07 INFO - PROCESS | 12593 | RSS: Main: 140169216 12:24:07 INFO - PROCESS | 12593 | 12:27:08 INFO - PROCESS | 12593 | Cycle 1(1): loaded http://localhost:52101/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52101/tests/dromaeo/cssquery-ext.html) 12:27:10 INFO - PROCESS | 12593 | RSS: Main: 289062912 12:27:10 INFO - PROCESS | 12593 | 12:28:56 INFO - PROCESS | 12593 | ************************* 12:28:56 INFO - PROCESS | 12593 | A coding exception was thrown and uncaught in a Task. 12:28:56 INFO - PROCESS | 12593 | 12:28:56 ERROR - PROCESS | 12593 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:28:56 INFO - PROCESS | 12593 | Full stack: 12:28:56 INFO - PROCESS | 12593 | ************************* 12:28:56 INFO - PROCESS | 12593 | ************************* 12:28:56 INFO - PROCESS | 12593 | A coding exception was thrown and uncaught in a Task. 12:28:56 INFO - PROCESS | 12593 | 12:28:56 ERROR - PROCESS | 12593 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:28:56 INFO - PROCESS | 12593 | Full stack: 12:28:56 INFO - PROCESS | 12593 | ************************* 12:28:56 INFO - PROCESS | 12593 | ************************* 12:28:56 INFO - PROCESS | 12593 | A coding exception was thrown and uncaught in a Task. 12:28:56 INFO - PROCESS | 12593 | 12:28:56 ERROR - PROCESS | 12593 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:28:56 INFO - PROCESS | 12593 | Full stack: 12:28:56 INFO - PROCESS | 12593 | ************************* 12:28:56 INFO - PROCESS | 12593 | ************************* 12:28:56 INFO - PROCESS | 12593 | A coding exception was thrown and uncaught in a Task. 12:28:56 INFO - PROCESS | 12593 | 12:28:56 ERROR - PROCESS | 12593 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:28:56 INFO - PROCESS | 12593 | Full stack: 12:28:56 INFO - PROCESS | 12593 | ************************* 12:30:12 INFO - PROCESS | 12593 | Cycle 1(1): loaded http://localhost:52101/tests/dromaeo/cssquery-ext.html (next: http://localhost:52101/tests/dromaeo/cssquery-jquery.html) 12:30:12 INFO - PROCESS | 12593 | RSS: Main: 216801280 12:30:12 INFO - PROCESS | 12593 | 12:33:14 INFO - PROCESS | 12593 | Cycle 1(1): loaded http://localhost:52101/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52101/tests/dromaeo/cssquery-mootools.html) 12:33:15 INFO - PROCESS | 12593 | RSS: Main: 210165760 12:33:15 INFO - PROCESS | 12593 | 12:36:17 INFO - PROCESS | 12593 | Cycle 1(1): loaded http://localhost:52101/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52101/tests/dromaeo/cssquery-prototype.html) 12:36:17 INFO - PROCESS | 12593 | RSS: Main: 171446272 12:36:17 INFO - PROCESS | 12593 | 12:39:20 INFO - PROCESS | 12593 | Cycle 1(1): loaded http://localhost:52101/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52101/tests/dromaeo/cssquery-yui.html) 12:39:21 INFO - PROCESS | 12593 | RSS: Main: 177041408 12:39:21 INFO - PROCESS | 12593 | 12:42:23 INFO - PROCESS | 12593 | Cycle 1(1): loaded http://localhost:52101/tests/dromaeo/cssquery-yui.html (next: http://localhost:52101/tests/dromaeo/cssquery-dojo.html) 12:42:24 INFO - PROCESS | 12593 | RSS: Main: 262983680 12:42:24 INFO - PROCESS | 12593 | 12:42:24 INFO - PROCESS | 12593 | __start_tp_report 12:42:24 INFO - PROCESS | 12593 | _x_x_mozilla_page_load 12:42:24 INFO - PROCESS | 12593 | _x_x_mozilla_page_load_details 12:42:24 INFO - PROCESS | 12593 | |i|pagename|runs| 12:42:24 INFO - PROCESS | 12593 | |0;dojo.html;3388.4409072503036;3423.4693622488517;3424.195314101184;3446.5691788526433;3475.7914525128494;5713.171590119436;5790.76836926523;5805.883882322351;5806.680632565202;5807.941920580793;5803.651780893149;5839.0949402842625;5841.269841269841;5845.7369418376165;5846.766129354821;5144.691318520891;5149.665271757342;5167.612429067835;5234.73826308685;5250.921236181461;5056.84829455117;5244.737763111829;5256.106461901467;5256.579473642099;5285.6300058995885;5112.156494178456;5128.769205385766;5183.2743415921805;5257.684538927658;5267.710275934822;5254.238135470355;5277.788888444457;5279.498447647467;5283.392409872876;5286.259923610698;5136.946925880223;5190.7404629768735;5194.288668814375;5201.739913004372;5241.135212689902;5493.121100623921;5530.834074977757;5731.283589551306;5765.365809760923;5787.33445653751;6228.5951413158455;6250.218722659668;6258.24901011877;6281.842953926142;6292.150559674477;6250.093736408241;6264.310925798159;6266.154069200645;6280.340564240734;6283.088952101965;5623.325200975868;5661.830145095612;5732.656040637575;5753.223314852441;5879.2944846618675;6336.398042185985;6406.583572067801;6410.903836442457;6412.615243085336;6441.516886233551;6114.602550834182;6138.07928810673;6174.104754810527;6184.598001130003;6194.0708893666315;6070.878582428415;6130.509559235251;6279.623222606659;6289.559730818799;6294.527910406738;6144.446999770043;6168.9691551541955;6175.69121543921;6196.194494715658;6201.379862013853;5975.282966044103;6031.7587296507645;6033.215681961317;6269.279032911182;6288;4654.720716757005;4685.437747470325;4690.273007683746;4692.1554120258;4715.717056976592;4093.406456063854;4184.184084103632;4195.160967806451;4208.516020657602;4236.703430759817;5802.216700745345;5819.94180058194;5843.707814609253;5912.526997840162;5938.346781853993;6153.938460615426;6187.721552530147;6189.938100618936;6193.102000209944;6194.132821405007;5658.122990936329;5906.852328691817;5907;5967.492763115097;5968.074948383008;5041.647084704109;5104.183330667075;5142.30578871855;5210.062188806051;5219.347581552306;6487.870242595216;6519.576227545194;6557.737690492327;6560.86878262432;6569.474442044625;6322.905156422749;6323.36766323364;6329.588576742497;6330.746770129143;6331.3352098029045;6216.564840461125;6257.624542527463;6301.180846489927;6353.7140828661895;6478;6115.510759139347;6122.540809439399;6169.136320915165;6179.81460556184;6185.072239164161;6229.875402491834;6233.563650544418;6237.75048998035;6241.687915604292;6244.750209991549;5804.361520232856;5866.2960444746905;5963.224780778471;6033.125196846345;6082.817515474543;6077;6105.725242364193;6114.844865410725;6115.270764408881;6138.447539721446;6138.987067133873;6143.477804386677;6147.262328520427;6148.385161483816;6149.538784591263;5867.442592953663;5889.116632505158;5923.7926672566255;5935.851603709942;6206.534509911684;5875.41245875409;5887.470127688529;5889.793857214977;5891.1752354671235;5892.027815410581 12:42:24 INFO - PROCESS | 12593 | |1;ext.html;722.090166390348;791.70311133325;799.7960520067384;864.3171894203575;871.4684042733927;29588;42082.57917420829;42742.358864617054;42869.57130428695;42889.571104288945;43185.35221971665;43469.04389302481;43469.34795978063;43488.130237395315;43505.78247108768;7502.17476077631;7653.349465295457;9009.324300677443;9153.95423022884;9154;10483.423411712352;10725.249232553724;10726.088282495997;10728.946355268212;10745.19411044171;10904.291221070645;10946.014858662718;10952.45237738114;10955.123590112811;10966.616168434106;10868.61959831406;10875.728106797314;10907.890921090768;10910.945445272802;10920.945395272973;10232.181425485944;10256.538455769509;10265.846012309821;10289.794204115951;10294.17646588271;40972.59027409717;41165.58834411648;41225;41231;41239.1752164958;4049.0687141957515;4051.5948405159543;4093.7339072960162;4094.0583665757044;4152.190322887008;29310;30001.699983000108;30034;30193.698063019307;31003.37993240145;4390.956090439086;4413.117376524708;4444.422225110715;4468.061707041526;4489.932651010237;224703;226227;226314;226488;226882.86558567267;52070.47929520656;52250.73874630602;52259.47740522622;52261.47738522642;52329.21506177334;54313.45686543163;54383;54453.72773136188;54486.727566361915;54605.1809222862;15775.29011194476;16124;16160.838391615933;16174.27215775293;16182.271797769128;11299.265547739371;11612;13511.662208444672;14119.576412707635;14139.929300353433;13239.404226809624;13255.674432556667;13261.535846245335;13307.600771976857;13370.26463544495;5538.307711536058;5602.803901863415;5653.632513886584;5673.971630141906;5696.943030569641;1028.0593257169755;1031.138998935882;1032.055669062799;1035.8342665173536;1041.5000799616228;27777.305567360978;27816.16551503458;27957.580636290473;28050.158495245174;28424;1085.4084523934475;1096.413418820943;1108.772701596171;1125.8423820665114;1131.8585176852894;14590.489332873298;14780.556583302518;14843.257837108318;15006.774898376532;15058.698826023417;304106;425319;426585;431906;432599;50145.24782128271;50416.747916260916;50425;50447.74776126096;50503.49496505062;1492.7910092587047;1509.803725515676;1513.3190064471032;1524.6645737937636;1529.7093552225042;26232.606510902733;27255.454890902467;27939.860300698365;28011.719882801728;28036.85981570079;36107.81946090358;36127.096822579646;36260;36278.8186059068;36308.81845590755;306926;307106;307969;308603;309065;24033.879830600734;24733.134340298002;27581.58627620547;28367.858160709064;28589.28526786847;36727.632723672425;36753.63246367502;37193.256134877694;37199.81400092982;37576.62423375731;28673.713262867106;29084.27289317784;29652.110436686933;29659.85170074222;29675.55486667745;29050.854745726137;29511.704882951755;29676.703232967393;29800.70199298066;29930.4013919716;33123.83438082891;33490.665093348754;33570.832145839115;34182.8290858554;34303.4854477178;27455.588166177924;27803.16590502288;28103.43793124167;28254.43491130207;28371.57442638321;23398.76601233966;23533.882330588236;23552.76447235506;23961.28116156518;23991.52016959686 12:42:24 INFO - PROCESS | 12593 | |2;jquery.html;468.15498026062954;468.4168210577831;473.13888722524973;474.3501403077784;484.8690853469563;16951.915240423798;17357.39249126281;21762.564748705037;22024.88987555064;22206.777932220673;21758.89120554399;21946.780532194673;22292.219772307973;22313.44216394591;22456.438589035242;6586.769463068782;6691.297413771557;6757.121574195372;6783.457323414115;6885.621290829003;6701.128853249071;6809.1828980522405;6812.2506524282535;6819.965900170492;7217.711291548332;7699.499532530368;7726.227377262285;7732.226777322279;7738.883916741253;7752.496087754278;7723.420743444236;7887.132415434297;8198.303144232717;8213.096559378464;8227.42408031438;7921.722739704082;7985.680572777082;8075.717349892726;8082.313003394718;8173.591320433955;29181.416371672447;29292;29418.411631767245;29653.70346296531;30184.8490757547;3708;3718.2563487302646;3783.810809459516;3795.221979494197;3808.6953043756434;17879.73180402295;18053.7291940621;18105.366312179143;18195.63608727818;18761.53096172593;2219.8890055497322;2389.9880500597387;2497.438076432806;2504.4991001799713;2602.46649436865;118354.40822795917;118549.40725296404;120690.3965480167;122279;122525;21656.56686866254;22013.889930550566;22714.09143634236;22890.54218915646;23046.423839403815;43359.56640433555;43838;44065.77967110208;44133.33799993002;44329.55670443319;12048.638540843789;12092.214006089576;12136;12238.082143839247;12270.938645306716;9915.058069483388;9926.255530835217;9935.80128397428;10178;10203.163430582417;10816.945915270373;11229.550817967352;11353.772924541463;11579.536818527333;11780.70548236301;4429.424174857248;4468.77243359659;4605.240135377625;4650.697704649187;4657.580817726421;960.8366577681811;963.0562049191691;977.4917043137528;983.6016413352604;992.5682328187262;20952.371428857157;20999.580008399746;21176.576468470852;21207;21236.25673101434;978.7406337320616;983.331334692416;986.7681094942683;993.5082134343546;998.6854140945579;10754.354738715701;11500.712482187844;11580.247283926687;11623.53505859773;11627.418629068512;448275;656808;662543;693530;716209;73684;101909.49045254727;102215.48892255491;109656;121379.39310303392;1285.3123578885252;1333.3533236380347;1347.5216298214189;1355.4307190979812;1363.5432130236284;18427.447176584723;18794.15426305847;18872.150753215865;18929.148188331834;19241.037948102257;36593.81703091467;39344.2131157381;46197;50343.74828125836;51732.741336293075;65115.348846510926;65834.34165658282;66380;66949.66525167343;67172.664136679;12184.57353992606;12313.44589493457;12327.75344493123;12413.193142445713;12491.687707807378;13156.671083222613;13163.210207387587;13311.400986955416;13551.661208469866;13817.79273310881;11577.189596728149;12451.501939922302;12603.180793248775;12653.367331633566;12780.552680656505;10842.078423334106;10902.073323767569;10921;11269.605563805228;11296.548138074713;12121.45453454579;12586.118971671895;12589.496420143092;12797;13070.738585228051;12062.396880156133;12309.753804923672;12451.128421010442;12460.563880264148;12735.426905789076;11445.542178312775;11540.134489913122;11879.22785018971;11902.226355286879;11903.583374581847 12:42:24 INFO - PROCESS | 12593 | |3;mootools.html;1510.8262549806775;1570.8664763495103;1571.4657016614349;1574.7480403135496;1584.1920620483556;10854.891451085487;11594.536218551259;11594.710132246699;11599.536018559258;11608.013318867906;10991.285566438177;11322.433878306072;11327.660170194888;11337.26307789993;11372.147088968319;2496.2636022373445;2541.606051062088;2544.4783819316995;2545.8599777012255;2547.605121206216;2445.364205306624;2496.7628075332814;2532.075792335803;2540.733223011585;2546.121588052119;2475.121331927167;2497.0136795965673;2497.1010436242937;2504.8998040078377;2516.6476693262957;2317.895694693726;2333.4166458385403;2394.162043284845;2460.987695061522;2468.0744720729726;2411.2645643078854;2427.1019722702536;2436.427439551704;2437.4150203951103;2437.500312435964;11378.886211137948;11536.25014374063;11543.595974140948;11544.826827597593;11569.826452603218;5811.15738217956;5972.910406343864;5980.252468441445;5989.431004054608;6004.5796794224425;3423.1613254752747;3454.602720687103;3500.982495087508;3664.395374763161;3740.214554943465;1483.821941367032;2296.850704704206;2349.835511514195;2378.9167379141645;2384.5826980278557;15123.621909452242;15502.767458488019;16825.074620895968;17712.29150833965;17780.199891004937;4657.743824089707;4768.7615619218905;4779;4780.211265141213;4781.617470602343;13421.463141474233;13421.865781342256;13424.463021479032;13425;13429.127106738031;5091.745412729349;5246.31797866275;5956;6018.368071352533;6133.080037994337;5456.91814622781;5480.095784195642;5541.251930989265;5628.549716022709;5636.887262254732;4377.387165796791;4461.754603496839;4518.186726389282;4547.681662283609;4558.726476411426;1211.6304527119314;1214.878512148778;1216.4525963316366;1234.4074844074714;1234.6172686467044;948.6679662118204;954.665866946577;966.0725703324887;993.0020329568881;1099.1591432554103;7260.495826585275;7643.121041080236;7681.577513236825;7685.884711729329;7752.224777522202;1096.9122470202524;1112.9666110016713;1119.5745616665615;1133.5465813674596;1154.6766905266538;4775.570198682135;4795.088933102699;4850.223964165716;4891.364122664031;5020.824271150565;14326.928365358106;14770.852291477162;14847.70304593902;14884.479043233652;14902.850971490147;13965.930170349084;14436.772874305801;14485.92757036208;14520.636984075483;14775.261236938326;10696.03735663794;10871.565137394573;10878.020978112008;10879.401632910265;10962.725931851608;7401.8149546261775;7681.347085497795;7708.614569271626;7798.610069496616;8009.038915330198;12983.870161298266;13002.674933126747;13030.674233144247;13075.607731768063;13264.204147751167;12890.48438062467;12978.351082446028;13132;13429.865701342862;13700.040997130105;9813.705588832347;9863.161631261422;9866.851997219905;9942.403455792675;9968.40189588627;10462.372257664565;10506.684799456028;10550.577976880839;10598.682039538826;10697.358158510515;9098.863517047117;9138.17756401927;9154.221891139328;9248.907510924804;9337.066293370608;9104.226140777842;9121.635134594808;9211.309151813506;9225.630974760934;9358.11097945694;7969;8095.797605059921;8176.7955801105445;8179.0594081681365;8193.34453243751;6346.301906790157;6397.360263973565;6417.486601071996;6458.677066146768;6495.837604059747;6452.612843229421;6457.903131453126;6554.63949482783;6596.967015165085;6668.833279167866 12:42:24 INFO - PROCESS | 12593 | |4;prototype.html;421.32377534057815;439.802089059923;442.12238706168273;444.65539207114483;445.3653543700227;5259.55293800027;5286.286351342567;5286.392064912537;5291;5322.749153948819;4983.800647974077;5241.5806735461165;5247.86880327992;5251.973740131294;5262.315898933133;4361.716488428242;4369.235383807837;4386.824527018915;4391.780410979454;4396.406485124515;4387.473503179607;4390.1878152541885;4390.868273951771;4393.846215382462;4411.580899814512;4341.435613370273;4360.825566977317;4367.585079417466;4368.191884501377;4381.583749543788;4167.249895018896;4315.223259813248;4329.891752706176;4354.388876115826;4374.103308821684;4060.330045542482;4060.715749897479;4080.4491393661774;4102.7691692492435;4104.850641820295;5061.316722242487;5121.743912804345;5143.665661731976;5146.886133888981;5154.175331946871;4974.925376119361;5029.597632189452;5067.898642027176;5070.442251352349;5079.9426232375945;5459.3994660587305;5483.972580137113;5515.944840551583;5518.751656175482;5574.60977731559;4574.382458368111;4615.399998000238;4627.120847039052;4630.402511133653;4637.6289896808175;5413.702246376448;5525.502704756551;5539.25220095286;5621.2973378328115;5751.367349591542;4660.7669616519715;4673.948361618609;4689.484156742756;4708.788104535304;4713.3872596562915;4763.809447622126;4770.856874293777;4776.498467660846;4784.880377990578;4797.95202047982;4995.550400463976;5005.199168133081;5009.824656137091;5035.697858128524;5088.338515992971;5387.46125387466;5390.628123625592;5418.756155972991;5427.185922111715;5440.537554307849;1618.2070785315111;1633.55893908644;1647.5798671338732;1648.5136884619067;1649.5876030992251;1266.290877108822;1275.9234445933273;1280.9039322050883;1297.4615534553243;1315.4672357694958;5377.892442151135;5380.542653874386;5385.461453854583;5392.245085688007;5402.891942161214;5673.574481913873;5694.60137790359;5698.091139468324;5734.251053428744;5750.511206547407;5188.870278242998;5329.173978033411;5351.224072509464;5429.809956651498;5446.046941785172;3009.172477568686;3015.3969206158854;3018.320877802521;3026.3947210557976;3036.2409397650586;5642.153676948491;5923.911141332884;5929;5942.227510423703;5982.4316689914385;5729.742161602738;5764.365919748824;5815.41845815415;5844.714162884244;5877.294724633071;5895.263092113486;5945.851353716105;5949.66132620157;5981.431763982415;6029.487493563009;5489.231507589021;5719.656820590778;5721.170430287668;5723.656580605177;5778.27771528559;5441.972790136024;5678.119891416837;5679.204911312337;5679.829605111853;5693.5160511357;5810.833095962795;5824.534037276923;5828.970855145697;5886.911696324473;5897.67562784051;4749.596284315872;4794.041191761731;4827.734474603931;4836.250381190921;4851.830185943475;5444.918326225189;5633.746481408429;5675.404082571271;5796.971015145066;5833.620814647034;5516.558675306046;5522.41043321844;5556.91664625039;5562.276904002454;5581.6371935824045;5524.613277070566;5561.1658251261015;5561.277033985556;5590.357108932525;5596.720163991703;5299.761510731949;5500.5874559409;5503.174523821459;5529.198266251451;5540.806071787468;5517.337919449692;5539.25220095282;5542.639728417801;5547.278853748987;5565.7217139143695;5304.28392167068;5417.729113544228;5440;5478.945210547843;5491.313585801775 12:42:24 INFO - PROCESS | 12593 | |5;yui.html;817.5952903312865;860.9182127697867;910.5902343945224;931.8555623878297;938.8685584018234;15850.369812218676;18262.726059109107;18506.722399164024;18517.722234166496;18521;16093.597660058504;16204;16221.107839068847;16260.349586016575;16346;881.7266647339313;886.4725488334462;887.3699673232013;888.1606881496988;888.5956889615242;879.1604018162672;899.3524662243175;900.3922352412128;901.4636291406603;907.8547432410783;929.2194556572477;929.5445231836385;934.1312579301247;935.046252822121;935.723961431376;920.9171174594252;921.2675922641482;921.5806807902421;921.617528725578;926.5598840550751;682.4165338635445;682.5426963934176;682.7234969837224;682.747383468115;682.7985744205471;16582.419615313527;17016.404425845038;17064.976101433953;17314.393996210074;17974.640507189913;14201.85798142026;16177.191140443048;16251.268692908847;16392.590185245346;16535.917320413322;14259.144451332953;14657.387687354383;14805.093181851962;15114.244287785566;15136.318865651072;4014.1770936958146;4048.210598933209;4083.1833633273463;4961.032598643266;4962.0324036812835;49812.50187498115;53347.733261333444;53541.46458535443;60610.69694651499;83877.16122838754;11993.160478766402;12260.75478490443;12484.68788280282;12537.811932821014;12549.87450125487;22078.11687532513;22334.21830235934;22561.774382256295;22681;22759;8732.8690069649;8927.285817134614;9004.954975225082;9007.459552426744;9114;4689.226277664147;4864.513548645107;4914.451947636555;4978.228374601942;5025;3786.394176931733;3877.185790983925;3894.337962546374;3897.6492115709157;3904.04350934023;639.5043841023152;644.446883011939;648.597869321024;657.6580178307349;665.9933400666029;759.5974133709143;760.5170716594891;761.6268028666025;774.9496282741604;778.8636988526985;11080.335179889233;11399.601013964471;11415.42922853854;12024.819627705592;12114.273143611412;762.523422860712;762.8741257692529;780.1379475679406;781.5037451218517;786.1273985875673;5215.947840521622;5427.85599688121;5437.211604317431;5511.090670039399;5538.556915446755;85177.57411212905;86800;87730;89625;90482;44889.77555112204;45831;59392;75312.62343688247;76284;64266.6786666073;65569.34430655632;65707.34292657107;65743.34256657468;66226.33773662298;10222.080012798884;11027.17296202772;11029.117670586173;12049.819252711392;12068.517259309529;42837.14325713531;43130.353044704985;43339;43633.345499816896;44259.33610995773;47830;57562.7121864388;58934.41065589461;59038.70480647569;59279.407205929114;13641.181529108286;13860.861391386361;13924;14162.716745665235;14195.716085678436;15243;15306.387744490095;16263.512094637179;16505.33978640841;16969.915150424167;15650.51647200723;15939.936184677817;16066.839331606534;16651.666966660356;16686;15031.173285469404;15589.29848156813;15596.766048509055;15616.297266622802;15660.13869237203;15038.54884353471;15052.698946020799;15206.619834504227;16308.67382652364;16343.264553094901;11328.60349887783;11550;11689.123315751183;11812.70468238301;12100.152989290666;13082.934585327013;13254.734905301648;13291.734165316833;13352.198868067948;13497.257650829299 12:42:24 INFO - PROCESS | 12593 | __end_tp_report 12:42:24 INFO - PROCESS | 12593 | __start_cc_report 12:42:24 INFO - PROCESS | 12593 | _x_x_mozilla_cycle_collect,1427 12:42:24 INFO - PROCESS | 12593 | __end_cc_report 12:42:24 INFO - PROCESS | 12593 | __startTimestamp1491853344234__endTimestamp 12:42:24 INFO - PROCESS | 12593 | 12:42:24 INFO - PROCESS | 12593 | ------- Summary: start ------- 12:42:24 INFO - PROCESS | 12593 | Number of tests: 6 12:42:24 INFO - PROCESS | 12593 | 12:42:24 INFO - PROCESS | 12593 | [#0] dojo.html Cycles:165 Average:5748.38 Median:5935.85 stddev:673.21 (11.3%) stddev-sans-first:649.32 12:42:24 INFO - PROCESS | 12593 | Values: 3388.4 3423.5 3424.2 3446.6 3475.8 5713.2 5790.8 5805.9 5806.7 5807.9 5803.7 5839.1 5841.3 5845.7 5846.8 5144.7 5149.7 5167.6 5234.7 5250.9 5056.8 5244.7 5256.1 5256.6 5285.6 5112.2 5128.8 5183.3 5257.7 5267.7 5254.2 5277.8 5279.5 5283.4 5286.3 5136.9 5190.7 5194.3 5201.7 5241.1 5493.1 5530.8 5731.3 5765.4 5787.3 6228.6 6250.2 6258.2 6281.8 6292.2 6250.1 6264.3 6266.2 6280.3 6283.1 5623.3 5661.8 5732.7 5753.2 5879.3 6336.4 6406.6 6410.9 6412.6 6441.5 6114.6 6138.1 6174.1 6184.6 6194.1 6070.9 6130.5 6279.6 6289.6 6294.5 6144.4 6169.0 6175.7 6196.2 6201.4 5975.3 6031.8 6033.2 6269.3 6288.0 4654.7 4685.4 4690.3 4692.2 4715.7 4093.4 4184.2 4195.2 4208.5 4236.7 5802.2 5819.9 5843.7 5912.5 5938.3 6153.9 6187.7 6189.9 6193.1 6194.1 5658.1 5906.9 5907.0 5967.5 5968.1 5041.6 5104.2 5142.3 5210.1 5219.3 6487.9 6519.6 6557.7 6560.9 6569.5 6322.9 6323.4 6329.6 6330.7 6331.3 6216.6 6257.6 6301.2 6353.7 6478.0 6115.5 6122.5 6169.1 6179.8 6185.1 6229.9 6233.6 6237.8 6241.7 6244.8 5804.4 5866.3 5963.2 6033.1 6082.8 6077.0 6105.7 6114.8 6115.3 6138.4 6139.0 6143.5 6147.3 6148.4 6149.5 5867.4 5889.1 5923.8 5935.9 6206.5 5875.4 5887.5 5889.8 5891.2 5892.0 12:42:24 INFO - PROCESS | 12593 | 12:42:24 INFO - PROCESS | 12593 | [#1] ext.html Cycles:180 Average:46579.79 Median:29620.06 stddev:84990.42 (286.9%) stddev-sans-first:85159.10 12:42:24 INFO - PROCESS | 12593 | Values: 722.1 791.7 799.8 864.3 871.5 29588.0 42082.6 42742.4 42869.6 42889.6 43185.4 43469.0 43469.3 43488.1 43505.8 7502.2 7653.3 9009.3 9154.0 9154.0 10483.4 10725.2 10726.1 10728.9 10745.2 10904.3 10946.0 10952.5 10955.1 10966.6 10868.6 10875.7 10907.9 10910.9 10920.9 10232.2 10256.5 10265.8 10289.8 10294.2 40972.6 41165.6 41225.0 41231.0 41239.2 4049.1 4051.6 4093.7 4094.1 4152.2 29310.0 30001.7 30034.0 30193.7 31003.4 4391.0 4413.1 4444.4 4468.1 4489.9 224703.0 226227.0 226314.0 226488.0 226882.9 52070.5 52250.7 52259.5 52261.5 52329.2 54313.5 54383.0 54453.7 54486.7 54605.2 15775.3 16124.0 16160.8 16174.3 16182.3 11299.3 11612.0 13511.7 14119.6 14139.9 13239.4 13255.7 13261.5 13307.6 13370.3 5538.3 5602.8 5653.6 5674.0 5696.9 1028.1 1031.1 1032.1 1035.8 1041.5 27777.3 27816.2 27957.6 28050.2 28424.0 1085.4 1096.4 1108.8 1125.8 1131.9 14590.5 14780.6 14843.3 15006.8 15058.7 304106.0 425319.0 426585.0 431906.0 432599.0 50145.2 50416.7 50425.0 50447.7 50503.5 1492.8 1509.8 1513.3 1524.7 1529.7 26232.6 27255.5 27939.9 28011.7 28036.9 36107.8 36127.1 36260.0 36278.8 36308.8 306926.0 307106.0 307969.0 308603.0 309065.0 24033.9 24733.1 27581.6 28367.9 28589.3 36727.6 36753.6 37193.3 37199.8 37576.6 28673.7 29084.3 29652.1 29659.9 29675.6 29050.9 29511.7 29676.7 29800.7 29930.4 33123.8 33490.7 33570.8 34182.8 34303.5 27455.6 27803.2 28103.4 28254.4 28371.6 23398.8 23533.9 23552.8 23961.3 23991.5 12:42:24 INFO - PROCESS | 12593 | 12:42:24 INFO - PROCESS | 12593 | [#2] jquery.html Cycles:180 Average:37447.45 Median:22249.50 stddev:105883.64 (475.9%) stddev-sans-first:106144.26 12:42:24 INFO - PROCESS | 12593 | Values: 468.2 468.4 473.1 474.4 484.9 16951.9 17357.4 21762.6 22024.9 22206.8 21758.9 21946.8 22292.2 22313.4 22456.4 6586.8 6691.3 6757.1 6783.5 6885.6 6701.1 6809.2 6812.3 6820.0 7217.7 7699.5 7726.2 7732.2 7738.9 7752.5 7723.4 7887.1 8198.3 8213.1 8227.4 7921.7 7985.7 8075.7 8082.3 8173.6 29181.4 29292.0 29418.4 29653.7 30184.8 3708.0 3718.3 3783.8 3795.2 3808.7 17879.7 18053.7 18105.4 18195.6 18761.5 2219.9 2390.0 2497.4 2504.5 2602.5 118354.4 118549.4 120690.4 122279.0 122525.0 21656.6 22013.9 22714.1 22890.5 23046.4 43359.6 43838.0 44065.8 44133.3 44329.6 12048.6 12092.2 12136.0 12238.1 12270.9 9915.1 9926.3 9935.8 10178.0 10203.2 10816.9 11229.6 11353.8 11579.5 11780.7 4429.4 4468.8 4605.2 4650.7 4657.6 960.8 963.1 977.5 983.6 992.6 20952.4 20999.6 21176.6 21207.0 21236.3 978.7 983.3 986.8 993.5 998.7 10754.4 11500.7 11580.2 11623.5 11627.4 448275.0 656808.0 662543.0 693530.0 716209.0 73684.0 101909.5 102215.5 109656.0 121379.4 1285.3 1333.4 1347.5 1355.4 1363.5 18427.4 18794.2 18872.2 18929.1 19241.0 36593.8 39344.2 46197.0 50343.7 51732.7 65115.3 65834.3 66380.0 66949.7 67172.7 12184.6 12313.4 12327.8 12413.2 12491.7 13156.7 13163.2 13311.4 13551.7 13817.8 11577.2 12451.5 12603.2 12653.4 12780.6 10842.1 10902.1 10921.0 11269.6 11296.5 12121.5 12586.1 12589.5 12797.0 13070.7 12062.4 12309.8 12451.1 12460.6 12735.4 11445.5 11540.1 11879.2 11902.2 11903.6 12:42:24 INFO - PROCESS | 12593 | 12:42:24 INFO - PROCESS | 12593 | [#3] mootools.html Cycles:180 Average:7226.84 Median:2497.06 stddev:4493.82 (180.0%) stddev-sans-first:4485.90 12:42:24 INFO - PROCESS | 12593 | Values: 1510.8 1570.9 1571.5 1574.7 1584.2 10854.9 11594.5 11594.7 11599.5 11608.0 10991.3 11322.4 11327.7 11337.3 11372.1 2496.3 2541.6 2544.5 2545.9 2547.6 2445.4 2496.8 2532.1 2540.7 2546.1 2475.1 2497.0 2497.1 2504.9 2516.6 2317.9 2333.4 2394.2 2461.0 2468.1 2411.3 2427.1 2436.4 2437.4 2437.5 11378.9 11536.3 11543.6 11544.8 11569.8 5811.2 5972.9 5980.3 5989.4 6004.6 3423.2 3454.6 3501.0 3664.4 3740.2 1483.8 2296.9 2349.8 2378.9 2384.6 15123.6 15502.8 16825.1 17712.3 17780.2 4657.7 4768.8 4779.0 4780.2 4781.6 13421.5 13421.9 13424.5 13425.0 13429.1 5091.7 5246.3 5956.0 6018.4 6133.1 5456.9 5480.1 5541.3 5628.5 5636.9 4377.4 4461.8 4518.2 4547.7 4558.7 1211.6 1214.9 1216.5 1234.4 1234.6 948.7 954.7 966.1 993.0 1099.2 7260.5 7643.1 7681.6 7685.9 7752.2 1096.9 1113.0 1119.6 1133.5 1154.7 4775.6 4795.1 4850.2 4891.4 5020.8 14326.9 14770.9 14847.7 14884.5 14902.9 13965.9 14436.8 14485.9 14520.6 14775.3 10696.0 10871.6 10878.0 10879.4 10962.7 7401.8 7681.3 7708.6 7798.6 8009.0 12983.9 13002.7 13030.7 13075.6 13264.2 12890.5 12978.4 13132.0 13429.9 13700.0 9813.7 9863.2 9866.9 9942.4 9968.4 10462.4 10506.7 10550.6 10598.7 10697.4 9098.9 9138.2 9154.2 9248.9 9337.1 9104.2 9121.6 9211.3 9225.6 9358.1 7969.0 8095.8 8176.8 8179.1 8193.3 6346.3 6397.4 6417.5 6458.7 6495.8 6452.6 6457.9 6554.6 6597.0 6668.8 12:42:24 INFO - PROCESS | 12593 | 12:42:24 INFO - PROCESS | 12593 | [#4] prototype.html Cycles:180 Average:4819.29 Median:5302.02 stddev:1283.26 (24.2%) stddev-sans-first:1243.68 12:42:24 INFO - PROCESS | 12593 | Values: 421.3 439.8 442.1 444.7 445.4 5259.6 5286.3 5286.4 5291.0 5322.7 4983.8 5241.6 5247.9 5252.0 5262.3 4361.7 4369.2 4386.8 4391.8 4396.4 4387.5 4390.2 4390.9 4393.8 4411.6 4341.4 4360.8 4367.6 4368.2 4381.6 4167.2 4315.2 4329.9 4354.4 4374.1 4060.3 4060.7 4080.4 4102.8 4104.9 5061.3 5121.7 5143.7 5146.9 5154.2 4974.9 5029.6 5067.9 5070.4 5079.9 5459.4 5484.0 5515.9 5518.8 5574.6 4574.4 4615.4 4627.1 4630.4 4637.6 5413.7 5525.5 5539.3 5621.3 5751.4 4660.8 4673.9 4689.5 4708.8 4713.4 4763.8 4770.9 4776.5 4784.9 4798.0 4995.6 5005.2 5009.8 5035.7 5088.3 5387.5 5390.6 5418.8 5427.2 5440.5 1618.2 1633.6 1647.6 1648.5 1649.6 1266.3 1275.9 1280.9 1297.5 1315.5 5377.9 5380.5 5385.5 5392.2 5402.9 5673.6 5694.6 5698.1 5734.3 5750.5 5188.9 5329.2 5351.2 5429.8 5446.0 3009.2 3015.4 3018.3 3026.4 3036.2 5642.2 5923.9 5929.0 5942.2 5982.4 5729.7 5764.4 5815.4 5844.7 5877.3 5895.3 5945.9 5949.7 5981.4 6029.5 5489.2 5719.7 5721.2 5723.7 5778.3 5442.0 5678.1 5679.2 5679.8 5693.5 5810.8 5824.5 5829.0 5886.9 5897.7 4749.6 4794.0 4827.7 4836.3 4851.8 5444.9 5633.7 5675.4 5797.0 5833.6 5516.6 5522.4 5556.9 5562.3 5581.6 5524.6 5561.2 5561.3 5590.4 5596.7 5299.8 5500.6 5503.2 5529.2 5540.8 5517.3 5539.3 5542.6 5547.3 5565.7 5304.3 5417.7 5440.0 5478.9 5491.3 12:42:24 INFO - PROCESS | 12593 | 12:42:24 INFO - PROCESS | 12593 | [#5] yui.html Cycles:180 Average:18085.18 Median:43486.17 stddev:21746.52 (50.0%) stddev-sans-first:21768.86 12:42:24 INFO - PROCESS | 12593 | Values: 817.6 860.9 910.6 931.9 938.9 15850.4 18262.7 18506.7 18517.7 18521.0 16093.6 16204.0 16221.1 16260.3 16346.0 881.7 886.5 887.4 888.2 888.6 879.2 899.4 900.4 901.5 907.9 929.2 929.5 934.1 935.0 935.7 920.9 921.3 921.6 921.6 926.6 682.4 682.5 682.7 682.7 682.8 16582.4 17016.4 17065.0 17314.4 17974.6 14201.9 16177.2 16251.3 16392.6 16535.9 14259.1 14657.4 14805.1 15114.2 15136.3 4014.2 4048.2 4083.2 4961.0 4962.0 49812.5 53347.7 53541.5 60610.7 83877.2 11993.2 12260.8 12484.7 12537.8 12549.9 22078.1 22334.2 22561.8 22681.0 22759.0 8732.9 8927.3 9005.0 9007.5 9114.0 4689.2 4864.5 4914.5 4978.2 5025.0 3786.4 3877.2 3894.3 3897.6 3904.0 639.5 644.4 648.6 657.7 666.0 759.6 760.5 761.6 774.9 778.9 11080.3 11399.6 11415.4 12024.8 12114.3 762.5 762.9 780.1 781.5 786.1 5215.9 5427.9 5437.2 5511.1 5538.6 85177.6 86800.0 87730.0 89625.0 90482.0 44889.8 45831.0 59392.0 75312.6 76284.0 64266.7 65569.3 65707.3 65743.3 66226.3 10222.1 11027.2 11029.1 12049.8 12068.5 42837.1 43130.4 43339.0 43633.3 44259.3 47830.0 57562.7 58934.4 59038.7 59279.4 13641.2 13860.9 13924.0 14162.7 14195.7 15243.0 15306.4 16263.5 16505.3 16969.9 15650.5 15939.9 16066.8 16651.7 16686.0 15031.2 15589.3 15596.8 15616.3 15660.1 15038.5 15052.7 15206.6 16308.7 16343.3 11328.6 11550.0 11689.1 11812.7 12100.2 13082.9 13254.7 13291.7 13352.2 13497.3 12:42:24 INFO - PROCESS | 12593 | -------- Summary: end -------- 12:42:24 INFO - PROCESS | 12593 | 12:42:25 INFO - TEST-INFO | 12593: exit 0 12:42:25 INFO - TEST-OK | dromaeo_css | took 1116384ms 12:42:25 INFO - TEST-START | kraken 12:42:25 INFO - operating with platform_type : linux_ 12:42:25 INFO - Initialising browser for kraken test... 12:42:25 INFO - TEST-INFO | started process 12916 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpblLOA3/profile http://localhost:52101/getInfo.html) 12:42:29 INFO - PROCESS | 12916 | __metrics Screen width/height:1600/1200 12:42:29 INFO - PROCESS | 12916 | colorDepth:24 12:42:29 INFO - PROCESS | 12916 | Browser inner width/height: 1024/697 12:42:29 INFO - PROCESS | 12916 | __metrics 12:42:30 INFO - PROCESS | 12916 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:42:30 INFO - PROCESS | 12916 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:42:31 INFO - TEST-INFO | 12916: exit 0 12:42:31 INFO - Browser initialized. 12:42:31 INFO - Running cycle 1/1 for kraken test... 12:42:31 INFO - TEST-INFO | started process 13012 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpblLOA3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:42:43 INFO - PROCESS | 13012 | RSS: Main: 139681792 12:42:43 INFO - PROCESS | 13012 | 12:42:45 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52101/tests/kraken/driver.html?testName=audio-beat-detection) 12:42:46 INFO - PROCESS | 13012 | RSS: Main: 183726080 12:42:46 INFO - PROCESS | 13012 | 12:42:49 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52101/tests/kraken/driver.html?testName=audio-dft) 12:42:49 INFO - PROCESS | 13012 | RSS: Main: 174571520 12:42:49 INFO - PROCESS | 13012 | 12:42:52 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52101/tests/kraken/driver.html?testName=audio-fft) 12:42:53 INFO - PROCESS | 13012 | RSS: Main: 266264576 12:42:53 INFO - PROCESS | 13012 | 12:42:55 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52101/tests/kraken/driver.html?testName=audio-oscillator) 12:42:55 INFO - PROCESS | 13012 | RSS: Main: 171827200 12:42:55 INFO - PROCESS | 13012 | 12:42:57 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52101/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:58 INFO - PROCESS | 13012 | RSS: Main: 174653440 12:42:58 INFO - PROCESS | 13012 | 12:43:01 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52101/tests/kraken/driver.html?testName=imaging-darkroom) 12:43:02 INFO - PROCESS | 13012 | RSS: Main: 213622784 12:43:02 INFO - PROCESS | 13012 | 12:43:05 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52101/tests/kraken/driver.html?testName=imaging-desaturate) 12:43:06 INFO - PROCESS | 13012 | RSS: Main: 212725760 12:43:06 INFO - PROCESS | 13012 | 12:43:08 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52101/tests/kraken/driver.html?testName=json-parse-financial) 12:43:09 INFO - PROCESS | 13012 | RSS: Main: 211169280 12:43:09 INFO - PROCESS | 13012 | 12:43:11 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52101/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:43:11 INFO - PROCESS | 13012 | RSS: Main: 173285376 12:43:11 INFO - PROCESS | 13012 | 12:43:13 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52101/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:43:13 INFO - PROCESS | 13012 | RSS: Main: 179073024 12:43:13 INFO - PROCESS | 13012 | 12:43:15 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52101/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:43:16 INFO - PROCESS | 13012 | RSS: Main: 189440000 12:43:16 INFO - PROCESS | 13012 | 12:43:18 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52101/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:43:19 INFO - PROCESS | 13012 | RSS: Main: 198766592 12:43:19 INFO - PROCESS | 13012 | 12:43:22 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52101/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:43:23 INFO - PROCESS | 13012 | RSS: Main: 195284992 12:43:23 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | Cycle 1(1): loaded http://localhost:52101/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52101/tests/kraken/driver.html?testName=ai-astar) 12:43:24 INFO - PROCESS | 13012 | RSS: Main: 246185984 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | __start_tp_report 12:43:24 INFO - PROCESS | 13012 | _x_x_mozilla_page_load 12:43:24 INFO - PROCESS | 13012 | _x_x_mozilla_page_load_details 12:43:24 INFO - PROCESS | 13012 | |i|pagename|runs| 12:43:24 INFO - PROCESS | 13012 | |0;ai-astar;103;112;111;112;112;111;110;111;111;112 12:43:24 INFO - PROCESS | 13012 | |1;audio-beat-detection;138;116;117;159;116;118;120;119;138;115 12:43:24 INFO - PROCESS | 13012 | |2;audio-dft;160;161;159;161;161;163;162;162;159;160 12:43:24 INFO - PROCESS | 13012 | |3;audio-fft;77;76;76;112;75;76;77;75;91;76 12:43:24 INFO - PROCESS | 13012 | |4;audio-oscillator;82;82;112;86;83;82;99;82;82;111 12:43:24 INFO - PROCESS | 13012 | |5;imaging-gaussian-blur;113;113;113;113;113;113;112;113;112;112 12:43:24 INFO - PROCESS | 13012 | |6;imaging-darkroom;146;148;147;149;148;146;147;147;146;146 12:43:24 INFO - PROCESS | 13012 | |7;imaging-desaturate;94;94;94;94;94;94;94;94;95;93 12:43:24 INFO - PROCESS | 13012 | |8;json-parse-financial;56;57;56;57;57;62;59;56;55;57 12:43:24 INFO - PROCESS | 13012 | |9;json-stringify-tinderbox;53;52;53;53;52;53;56;54;54;53 12:43:24 INFO - PROCESS | 13012 | |10;stanford-crypto-aes;75;76;76;77;85;75;82;77;75;83 12:43:24 INFO - PROCESS | 13012 | |11;stanford-crypto-ccm;123;121;120;121;131;130;119;117;162;126 12:43:24 INFO - PROCESS | 13012 | |12;stanford-crypto-pbkdf2;166;174;167;167;165;167;165;164;169;169 12:43:24 INFO - PROCESS | 13012 | |13;stanford-crypto-sha256-iterative;60;62;65;59;59;59;58;59;58;58 12:43:24 INFO - PROCESS | 13012 | __end_tp_report 12:43:24 INFO - PROCESS | 13012 | __start_cc_report 12:43:24 INFO - PROCESS | 13012 | _x_x_mozilla_cycle_collect,1677 12:43:24 INFO - PROCESS | 13012 | __end_cc_report 12:43:24 INFO - PROCESS | 13012 | __startTimestamp1491853404983__endTimestamp 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | ------- Summary: start ------- 12:43:24 INFO - PROCESS | 13012 | Number of tests: 14 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.50 stddev:2.72 (2.4%) stddev-sans-first:0.71 12:43:24 INFO - PROCESS | 13012 | Values: 103.0 112.0 111.0 112.0 112.0 111.0 110.0 111.0 111.0 112.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:119.50 stddev:14.63 (12.2%) stddev-sans-first:14.81 12:43:24 INFO - PROCESS | 13012 | Values: 138.0 116.0 117.0 159.0 116.0 118.0 120.0 119.0 138.0 115.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.32 (0.8%) stddev-sans-first:1.36 12:43:24 INFO - PROCESS | 13012 | Values: 160.0 161.0 159.0 161.0 161.0 163.0 162.0 162.0 159.0 160.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#3] audio-fft Cycles:10 Average:81.10 Median:76.00 stddev:11.86 (15.6%) stddev-sans-first:12.48 12:43:24 INFO - PROCESS | 13012 | Values: 77.0 76.0 76.0 112.0 75.0 76.0 77.0 75.0 91.0 76.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#4] audio-oscillator Cycles:10 Average:90.10 Median:82.00 stddev:12.43 (15.2%) stddev-sans-first:12.84 12:43:24 INFO - PROCESS | 13012 | Values: 82.0 82.0 112.0 86.0 83.0 82.0 99.0 82.0 82.0 111.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#5] imaging-gaussian-blur Cycles:10 Average:112.70 Median:113.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 12:43:24 INFO - PROCESS | 13012 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 112.0 113.0 112.0 112.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#6] imaging-darkroom Cycles:10 Average:147.00 Median:147.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 12:43:24 INFO - PROCESS | 13012 | Values: 146.0 148.0 147.0 149.0 148.0 146.0 147.0 147.0 146.0 146.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 12:43:24 INFO - PROCESS | 13012 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 95.0 93.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:1.99 (3.5%) stddev-sans-first:2.06 12:43:24 INFO - PROCESS | 13012 | Values: 56.0 57.0 56.0 57.0 57.0 62.0 59.0 56.0 55.0 57.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#9] json-stringify-tinderbox Cycles:10 Average:53.30 Median:53.00 stddev:1.16 (2.2%) stddev-sans-first:1.22 12:43:24 INFO - PROCESS | 13012 | Values: 53.0 52.0 53.0 53.0 52.0 53.0 56.0 54.0 54.0 53.0 12:43:24 INFO - PROCESS | 13012 | 12:43:24 INFO - PROCESS | 13012 | [#10] stanford-crypto-aes Cycles:10 Average:78.10 Median:77.00 stddev:3.75 (4.9%) stddev-sans-first:3.81 12:43:24 INFO - PROCESS | 13012 | Values: 75.0 76.0 76.0 77.0 85.0 75.0 82.0 77.0 75.0 83.0 12:43:25 INFO - PROCESS | 13012 | 12:43:25 INFO - PROCESS | 13012 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:124.50 stddev:13.13 (10.5%) stddev-sans-first:13.85 12:43:25 INFO - PROCESS | 13012 | Values: 123.0 121.0 120.0 121.0 131.0 130.0 119.0 117.0 162.0 126.0 12:43:25 INFO - PROCESS | 13012 | 12:43:25 INFO - PROCESS | 13012 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.00 stddev:2.87 (1.7%) stddev-sans-first:3.00 12:43:25 INFO - PROCESS | 13012 | Values: 166.0 174.0 167.0 167.0 165.0 167.0 165.0 164.0 169.0 169.0 12:43:25 INFO - PROCESS | 13012 | 12:43:25 INFO - PROCESS | 13012 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.70 Median:59.00 stddev:2.21 (3.8%) stddev-sans-first:2.35 12:43:25 INFO - PROCESS | 13012 | Values: 60.0 62.0 65.0 59.0 59.0 59.0 58.0 59.0 58.0 58.0 12:43:25 INFO - PROCESS | 13012 | -------- Summary: end -------- 12:43:25 INFO - PROCESS | 13012 | 12:43:26 INFO - TEST-INFO | 13012: exit 0 12:43:26 INFO - TEST-OK | kraken | took 60982ms 12:43:26 INFO - SUITE-END | took 1177s 12:43:26 INFO - Completed test suite (00:19:37) 12:43:26 INFO - javascript benchmark 12:43:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7920.457161018024, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.4409072503036, 3423.4693622488517, 3424.195314101184, 3446.5691788526433, 3475.7914525128494, 5713.171590119436, 5790.76836926523, 5805.883882322351, 5806.680632565202, 5807.941920580793, 5803.651780893149, 5839.0949402842625, 5841.269841269841, 5845.7369418376165, 5846.766129354821, 5144.691318520891, 5149.665271757342, 5167.612429067835, 5234.73826308685, 5250.921236181461, 5056.84829455117, 5244.737763111829, 5256.106461901467, 5256.579473642099, 5285.6300058995885, 5112.156494178456, 5128.769205385766, 5183.2743415921805, 5257.684538927658, 5267.710275934822, 5254.238135470355, 5277.788888444457, 5279.498447647467, 5283.392409872876, 5286.259923610698, 5136.946925880223, 5190.7404629768735, 5194.288668814375, 5201.739913004372, 5241.135212689902, 5493.121100623921, 5530.834074977757, 5731.283589551306, 5765.365809760923, 5787.33445653751, 6228.5951413158455, 6250.218722659668, 6258.24901011877, 6281.842953926142, 6292.150559674477, 6250.093736408241, 6264.310925798159, 6266.154069200645, 6280.340564240734, 6283.088952101965, 5623.325200975868, 5661.830145095612, 5732.656040637575, 5753.223314852441, 5879.2944846618675, 6336.398042185985, 6406.583572067801, 6410.903836442457, 6412.615243085336, 6441.516886233551, 6114.602550834182, 6138.07928810673, 6174.104754810527, 6184.598001130003, 6194.0708893666315, 6070.878582428415, 6130.509559235251, 6279.623222606659, 6289.559730818799, 6294.527910406738, 6144.446999770043, 6168.9691551541955, 6175.69121543921, 6196.194494715658, 6201.379862013853, 5975.282966044103, 6031.7587296507645, 6033.215681961317, 6269.279032911182, 6288.0, 4654.720716757005, 4685.437747470325, 4690.273007683746, 4692.1554120258, 4715.717056976592, 4093.406456063854, 4184.184084103632, 4195.160967806451, 4208.516020657602, 4236.703430759817, 5802.216700745345, 5819.94180058194, 5843.707814609253, 5912.526997840162, 5938.346781853993, 6153.938460615426, 6187.721552530147, 6189.938100618936, 6193.102000209944, 6194.132821405007, 5658.122990936329, 5906.852328691817, 5907.0, 5967.492763115097, 5968.074948383008, 5041.647084704109, 5104.183330667075, 5142.30578871855, 5210.062188806051, 5219.347581552306, 6487.870242595216, 6519.576227545194, 6557.737690492327, 6560.86878262432, 6569.474442044625, 6322.905156422749, 6323.36766323364, 6329.588576742497, 6330.746770129143, 6331.3352098029045, 6216.564840461125, 6257.624542527463, 6301.180846489927, 6353.7140828661895, 6478.0, 6115.510759139347, 6122.540809439399, 6169.136320915165, 6179.81460556184, 6185.072239164161, 6229.875402491834, 6233.563650544418, 6237.75048998035, 6241.687915604292, 6244.750209991549, 5804.361520232856, 5866.2960444746905, 5963.224780778471, 6033.125196846345, 6082.817515474543, 6077.0, 6105.725242364193, 6114.844865410725, 6115.270764408881, 6138.447539721446, 6138.987067133873, 6143.477804386677, 6147.262328520427, 6148.385161483816, 6149.538784591263, 5867.442592953663, 5889.116632505158, 5923.7926672566255, 5935.851603709942, 6206.534509911684, 5875.41245875409, 5887.470127688529, 5889.793857214977, 5891.1752354671235, 5892.027815410581], "value": 5702.599900952673, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.090166390348, 791.70311133325, 799.7960520067384, 864.3171894203575, 871.4684042733927, 29588.0, 42082.57917420829, 42742.358864617054, 42869.57130428695, 42889.571104288945, 43185.35221971665, 43469.04389302481, 43469.34795978063, 43488.130237395315, 43505.78247108768, 7502.17476077631, 7653.349465295457, 9009.324300677443, 9153.95423022884, 9154.0, 10483.423411712352, 10725.249232553724, 10726.088282495997, 10728.946355268212, 10745.19411044171, 10904.291221070645, 10946.014858662718, 10952.45237738114, 10955.123590112811, 10966.616168434106, 10868.61959831406, 10875.728106797314, 10907.890921090768, 10910.945445272802, 10920.945395272973, 10232.181425485944, 10256.538455769509, 10265.846012309821, 10289.794204115951, 10294.17646588271, 40972.59027409717, 41165.58834411648, 41225.0, 41231.0, 41239.1752164958, 4049.0687141957515, 4051.5948405159543, 4093.7339072960162, 4094.0583665757044, 4152.190322887008, 29310.0, 30001.699983000108, 30034.0, 30193.698063019307, 31003.37993240145, 4390.956090439086, 4413.117376524708, 4444.422225110715, 4468.061707041526, 4489.932651010237, 224703.0, 226227.0, 226314.0, 226488.0, 226882.86558567267, 52070.47929520656, 52250.73874630602, 52259.47740522622, 52261.47738522642, 52329.21506177334, 54313.45686543163, 54383.0, 54453.72773136188, 54486.727566361915, 54605.1809222862, 15775.29011194476, 16124.0, 16160.838391615933, 16174.27215775293, 16182.271797769128, 11299.265547739371, 11612.0, 13511.662208444672, 14119.576412707635, 14139.929300353433, 13239.404226809624, 13255.674432556667, 13261.535846245335, 13307.600771976857, 13370.26463544495, 5538.307711536058, 5602.803901863415, 5653.632513886584, 5673.971630141906, 5696.943030569641, 1028.0593257169755, 1031.138998935882, 1032.055669062799, 1035.8342665173536, 1041.5000799616228, 27777.305567360978, 27816.16551503458, 27957.580636290473, 28050.158495245174, 28424.0, 1085.4084523934475, 1096.413418820943, 1108.772701596171, 1125.8423820665114, 1131.8585176852894, 14590.489332873298, 14780.556583302518, 14843.257837108318, 15006.774898376532, 15058.698826023417, 304106.0, 425319.0, 426585.0, 431906.0, 432599.0, 50145.24782128271, 50416.747916260916, 50425.0, 50447.74776126096, 50503.49496505062, 1492.7910092587047, 1509.803725515676, 1513.3190064471032, 1524.6645737937636, 1529.7093552225042, 26232.606510902733, 27255.454890902467, 27939.860300698365, 28011.719882801728, 28036.85981570079, 36107.81946090358, 36127.096822579646, 36260.0, 36278.8186059068, 36308.81845590755, 306926.0, 307106.0, 307969.0, 308603.0, 309065.0, 24033.879830600734, 24733.134340298002, 27581.58627620547, 28367.858160709064, 28589.28526786847, 36727.632723672425, 36753.63246367502, 37193.256134877694, 37199.81400092982, 37576.62423375731, 28673.713262867106, 29084.27289317784, 29652.110436686933, 29659.85170074222, 29675.55486667745, 29050.854745726137, 29511.704882951755, 29676.703232967393, 29800.70199298066, 29930.4013919716, 33123.83438082891, 33490.665093348754, 33570.832145839115, 34182.8290858554, 34303.4854477178, 27455.588166177924, 27803.16590502288, 28103.43793124167, 28254.43491130207, 28371.57442638321, 23398.76601233966, 23533.882330588236, 23552.76447235506, 23961.28116156518, 23991.52016959686], "value": 18345.250891452564, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.15498026062954, 468.4168210577831, 473.13888722524973, 474.3501403077784, 484.8690853469563, 16951.915240423798, 17357.39249126281, 21762.564748705037, 22024.88987555064, 22206.777932220673, 21758.89120554399, 21946.780532194673, 22292.219772307973, 22313.44216394591, 22456.438589035242, 6586.769463068782, 6691.297413771557, 6757.121574195372, 6783.457323414115, 6885.621290829003, 6701.128853249071, 6809.1828980522405, 6812.2506524282535, 6819.965900170492, 7217.711291548332, 7699.499532530368, 7726.227377262285, 7732.226777322279, 7738.883916741253, 7752.496087754278, 7723.420743444236, 7887.132415434297, 8198.303144232717, 8213.096559378464, 8227.42408031438, 7921.722739704082, 7985.680572777082, 8075.717349892726, 8082.313003394718, 8173.591320433955, 29181.416371672447, 29292.0, 29418.411631767245, 29653.70346296531, 30184.8490757547, 3708.0, 3718.2563487302646, 3783.810809459516, 3795.221979494197, 3808.6953043756434, 17879.73180402295, 18053.7291940621, 18105.366312179143, 18195.63608727818, 18761.53096172593, 2219.8890055497322, 2389.9880500597387, 2497.438076432806, 2504.4991001799713, 2602.46649436865, 118354.40822795917, 118549.40725296404, 120690.3965480167, 122279.0, 122525.0, 21656.56686866254, 22013.889930550566, 22714.09143634236, 22890.54218915646, 23046.423839403815, 43359.56640433555, 43838.0, 44065.77967110208, 44133.33799993002, 44329.55670443319, 12048.638540843789, 12092.214006089576, 12136.0, 12238.082143839247, 12270.938645306716, 9915.058069483388, 9926.255530835217, 9935.80128397428, 10178.0, 10203.163430582417, 10816.945915270373, 11229.550817967352, 11353.772924541463, 11579.536818527333, 11780.70548236301, 4429.424174857248, 4468.77243359659, 4605.240135377625, 4650.697704649187, 4657.580817726421, 960.8366577681811, 963.0562049191691, 977.4917043137528, 983.6016413352604, 992.5682328187262, 20952.371428857157, 20999.580008399746, 21176.576468470852, 21207.0, 21236.25673101434, 978.7406337320616, 983.331334692416, 986.7681094942683, 993.5082134343546, 998.6854140945579, 10754.354738715701, 11500.712482187844, 11580.247283926687, 11623.53505859773, 11627.418629068512, 448275.0, 656808.0, 662543.0, 693530.0, 716209.0, 73684.0, 101909.49045254727, 102215.48892255491, 109656.0, 121379.39310303392, 1285.3123578885252, 1333.3533236380347, 1347.5216298214189, 1355.4307190979812, 1363.5432130236284, 18427.447176584723, 18794.15426305847, 18872.150753215865, 18929.148188331834, 19241.037948102257, 36593.81703091467, 39344.2131157381, 46197.0, 50343.74828125836, 51732.741336293075, 65115.348846510926, 65834.34165658282, 66380.0, 66949.66525167343, 67172.664136679, 12184.57353992606, 12313.44589493457, 12327.75344493123, 12413.193142445713, 12491.687707807378, 13156.671083222613, 13163.210207387587, 13311.400986955416, 13551.661208469866, 13817.79273310881, 11577.189596728149, 12451.501939922302, 12603.180793248775, 12653.367331633566, 12780.552680656505, 10842.078423334106, 10902.073323767569, 10921.0, 11269.605563805228, 11296.548138074713, 12121.45453454579, 12586.118971671895, 12589.496420143092, 12797.0, 13070.738585228051, 12062.396880156133, 12309.753804923672, 12451.128421010442, 12460.563880264148, 12735.426905789076, 11445.542178312775, 11540.134489913122, 11879.22785018971, 11902.226355286879, 11903.583374581847], "value": 12063.074823485811, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1510.8262549806775, 1570.8664763495103, 1571.4657016614349, 1574.7480403135496, 1584.1920620483556, 10854.891451085487, 11594.536218551259, 11594.710132246699, 11599.536018559258, 11608.013318867906, 10991.285566438177, 11322.433878306072, 11327.660170194888, 11337.26307789993, 11372.147088968319, 2496.2636022373445, 2541.606051062088, 2544.4783819316995, 2545.8599777012255, 2547.605121206216, 2445.364205306624, 2496.7628075332814, 2532.075792335803, 2540.733223011585, 2546.121588052119, 2475.121331927167, 2497.0136795965673, 2497.1010436242937, 2504.8998040078377, 2516.6476693262957, 2317.895694693726, 2333.4166458385403, 2394.162043284845, 2460.987695061522, 2468.0744720729726, 2411.2645643078854, 2427.1019722702536, 2436.427439551704, 2437.4150203951103, 2437.500312435964, 11378.886211137948, 11536.25014374063, 11543.595974140948, 11544.826827597593, 11569.826452603218, 5811.15738217956, 5972.910406343864, 5980.252468441445, 5989.431004054608, 6004.5796794224425, 3423.1613254752747, 3454.602720687103, 3500.982495087508, 3664.395374763161, 3740.214554943465, 1483.821941367032, 2296.850704704206, 2349.835511514195, 2378.9167379141645, 2384.5826980278557, 15123.621909452242, 15502.767458488019, 16825.074620895968, 17712.29150833965, 17780.199891004937, 4657.743824089707, 4768.7615619218905, 4779.0, 4780.211265141213, 4781.617470602343, 13421.463141474233, 13421.865781342256, 13424.463021479032, 13425.0, 13429.127106738031, 5091.745412729349, 5246.31797866275, 5956.0, 6018.368071352533, 6133.080037994337, 5456.91814622781, 5480.095784195642, 5541.251930989265, 5628.549716022709, 5636.887262254732, 4377.387165796791, 4461.754603496839, 4518.186726389282, 4547.681662283609, 4558.726476411426, 1211.6304527119314, 1214.878512148778, 1216.4525963316366, 1234.4074844074714, 1234.6172686467044, 948.6679662118204, 954.665866946577, 966.0725703324887, 993.0020329568881, 1099.1591432554103, 7260.495826585275, 7643.121041080236, 7681.577513236825, 7685.884711729329, 7752.224777522202, 1096.9122470202524, 1112.9666110016713, 1119.5745616665615, 1133.5465813674596, 1154.6766905266538, 4775.570198682135, 4795.088933102699, 4850.223964165716, 4891.364122664031, 5020.824271150565, 14326.928365358106, 14770.852291477162, 14847.70304593902, 14884.479043233652, 14902.850971490147, 13965.930170349084, 14436.772874305801, 14485.92757036208, 14520.636984075483, 14775.261236938326, 10696.03735663794, 10871.565137394573, 10878.020978112008, 10879.401632910265, 10962.725931851608, 7401.8149546261775, 7681.347085497795, 7708.614569271626, 7798.610069496616, 8009.038915330198, 12983.870161298266, 13002.674933126747, 13030.674233144247, 13075.607731768063, 13264.204147751167, 12890.48438062467, 12978.351082446028, 13132.0, 13429.865701342862, 13700.040997130105, 9813.705588832347, 9863.161631261422, 9866.851997219905, 9942.403455792675, 9968.40189588627, 10462.372257664565, 10506.684799456028, 10550.577976880839, 10598.682039538826, 10697.358158510515, 9098.863517047117, 9138.17756401927, 9154.221891139328, 9248.907510924804, 9337.066293370608, 9104.226140777842, 9121.635134594808, 9211.309151813506, 9225.630974760934, 9358.11097945694, 7969.0, 8095.797605059921, 8176.7955801105445, 8179.0594081681365, 8193.34453243751, 6346.301906790157, 6397.360263973565, 6417.486601071996, 6458.677066146768, 6495.837604059747, 6452.612843229421, 6457.903131453126, 6554.63949482783, 6596.967015165085, 6668.833279167866], "value": 5568.277711253144, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.32377534057815, 439.802089059923, 442.12238706168273, 444.65539207114483, 445.3653543700227, 5259.55293800027, 5286.286351342567, 5286.392064912537, 5291.0, 5322.749153948819, 4983.800647974077, 5241.5806735461165, 5247.86880327992, 5251.973740131294, 5262.315898933133, 4361.716488428242, 4369.235383807837, 4386.824527018915, 4391.780410979454, 4396.406485124515, 4387.473503179607, 4390.1878152541885, 4390.868273951771, 4393.846215382462, 4411.580899814512, 4341.435613370273, 4360.825566977317, 4367.585079417466, 4368.191884501377, 4381.583749543788, 4167.249895018896, 4315.223259813248, 4329.891752706176, 4354.388876115826, 4374.103308821684, 4060.330045542482, 4060.715749897479, 4080.4491393661774, 4102.7691692492435, 4104.850641820295, 5061.316722242487, 5121.743912804345, 5143.665661731976, 5146.886133888981, 5154.175331946871, 4974.925376119361, 5029.597632189452, 5067.898642027176, 5070.442251352349, 5079.9426232375945, 5459.3994660587305, 5483.972580137113, 5515.944840551583, 5518.751656175482, 5574.60977731559, 4574.382458368111, 4615.399998000238, 4627.120847039052, 4630.402511133653, 4637.6289896808175, 5413.702246376448, 5525.502704756551, 5539.25220095286, 5621.2973378328115, 5751.367349591542, 4660.7669616519715, 4673.948361618609, 4689.484156742756, 4708.788104535304, 4713.3872596562915, 4763.809447622126, 4770.856874293777, 4776.498467660846, 4784.880377990578, 4797.95202047982, 4995.550400463976, 5005.199168133081, 5009.824656137091, 5035.697858128524, 5088.338515992971, 5387.46125387466, 5390.628123625592, 5418.756155972991, 5427.185922111715, 5440.537554307849, 1618.2070785315111, 1633.55893908644, 1647.5798671338732, 1648.5136884619067, 1649.5876030992251, 1266.290877108822, 1275.9234445933273, 1280.9039322050883, 1297.4615534553243, 1315.4672357694958, 5377.892442151135, 5380.542653874386, 5385.461453854583, 5392.245085688007, 5402.891942161214, 5673.574481913873, 5694.60137790359, 5698.091139468324, 5734.251053428744, 5750.511206547407, 5188.870278242998, 5329.173978033411, 5351.224072509464, 5429.809956651498, 5446.046941785172, 3009.172477568686, 3015.3969206158854, 3018.320877802521, 3026.3947210557976, 3036.2409397650586, 5642.153676948491, 5923.911141332884, 5929.0, 5942.227510423703, 5982.4316689914385, 5729.742161602738, 5764.365919748824, 5815.41845815415, 5844.714162884244, 5877.294724633071, 5895.263092113486, 5945.851353716105, 5949.66132620157, 5981.431763982415, 6029.487493563009, 5489.231507589021, 5719.656820590778, 5721.170430287668, 5723.656580605177, 5778.27771528559, 5441.972790136024, 5678.119891416837, 5679.204911312337, 5679.829605111853, 5693.5160511357, 5810.833095962795, 5824.534037276923, 5828.970855145697, 5886.911696324473, 5897.67562784051, 4749.596284315872, 4794.041191761731, 4827.734474603931, 4836.250381190921, 4851.830185943475, 5444.918326225189, 5633.746481408429, 5675.404082571271, 5796.971015145066, 5833.620814647034, 5516.558675306046, 5522.41043321844, 5556.91664625039, 5562.276904002454, 5581.6371935824045, 5524.613277070566, 5561.1658251261015, 5561.277033985556, 5590.357108932525, 5596.720163991703, 5299.761510731949, 5500.5874559409, 5503.174523821459, 5529.198266251451, 5540.806071787468, 5517.337919449692, 5539.25220095282, 5542.639728417801, 5547.278853748987, 5565.7217139143695, 5304.28392167068, 5417.729113544228, 5440.0, 5478.945210547843, 5491.313585801775], "value": 4451.493565318542, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.5952903312865, 860.9182127697867, 910.5902343945224, 931.8555623878297, 938.8685584018234, 15850.369812218676, 18262.726059109107, 18506.722399164024, 18517.722234166496, 18521.0, 16093.597660058504, 16204.0, 16221.107839068847, 16260.349586016575, 16346.0, 881.7266647339313, 886.4725488334462, 887.3699673232013, 888.1606881496988, 888.5956889615242, 879.1604018162672, 899.3524662243175, 900.3922352412128, 901.4636291406603, 907.8547432410783, 929.2194556572477, 929.5445231836385, 934.1312579301247, 935.046252822121, 935.723961431376, 920.9171174594252, 921.2675922641482, 921.5806807902421, 921.617528725578, 926.5598840550751, 682.4165338635445, 682.5426963934176, 682.7234969837224, 682.747383468115, 682.7985744205471, 16582.419615313527, 17016.404425845038, 17064.976101433953, 17314.393996210074, 17974.640507189913, 14201.85798142026, 16177.191140443048, 16251.268692908847, 16392.590185245346, 16535.917320413322, 14259.144451332953, 14657.387687354383, 14805.093181851962, 15114.244287785566, 15136.318865651072, 4014.1770936958146, 4048.210598933209, 4083.1833633273463, 4961.032598643266, 4962.0324036812835, 49812.50187498115, 53347.733261333444, 53541.46458535443, 60610.69694651499, 83877.16122838754, 11993.160478766402, 12260.75478490443, 12484.68788280282, 12537.811932821014, 12549.87450125487, 22078.11687532513, 22334.21830235934, 22561.774382256295, 22681.0, 22759.0, 8732.8690069649, 8927.285817134614, 9004.954975225082, 9007.459552426744, 9114.0, 4689.226277664147, 4864.513548645107, 4914.451947636555, 4978.228374601942, 5025.0, 3786.394176931733, 3877.185790983925, 3894.337962546374, 3897.6492115709157, 3904.04350934023, 639.5043841023152, 644.446883011939, 648.597869321024, 657.6580178307349, 665.9933400666029, 759.5974133709143, 760.5170716594891, 761.6268028666025, 774.9496282741604, 778.8636988526985, 11080.335179889233, 11399.601013964471, 11415.42922853854, 12024.819627705592, 12114.273143611412, 762.523422860712, 762.8741257692529, 780.1379475679406, 781.5037451218517, 786.1273985875673, 5215.947840521622, 5427.85599688121, 5437.211604317431, 5511.090670039399, 5538.556915446755, 85177.57411212905, 86800.0, 87730.0, 89625.0, 90482.0, 44889.77555112204, 45831.0, 59392.0, 75312.62343688247, 76284.0, 64266.6786666073, 65569.34430655632, 65707.34292657107, 65743.34256657468, 66226.33773662298, 10222.080012798884, 11027.17296202772, 11029.117670586173, 12049.819252711392, 12068.517259309529, 42837.14325713531, 43130.353044704985, 43339.0, 43633.345499816896, 44259.33610995773, 47830.0, 57562.7121864388, 58934.41065589461, 59038.70480647569, 59279.407205929114, 13641.181529108286, 13860.861391386361, 13924.0, 14162.716745665235, 14195.716085678436, 15243.0, 15306.387744490095, 16263.512094637179, 16505.33978640841, 16969.915150424167, 15650.51647200723, 15939.936184677817, 16066.839331606534, 16651.666966660356, 16686.0, 15031.173285469404, 15589.29848156813, 15596.766048509055, 15616.297266622802, 15660.13869237203, 15038.54884353471, 15052.698946020799, 15206.619834504227, 16308.67382652364, 16343.264553094901, 11328.60349887783, 11550.0, 11689.123315751183, 11812.70468238301, 12100.152989290666, 13082.934585327013, 13254.734905301648, 13291.734165316833, 13352.198868067948, 13497.257650829299], "value": 7891.923420869457, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1464.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 111.0, 112.0, 112.0, 111.0, 110.0, 111.0, 111.0, 112.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 116.0, 117.0, 159.0, 116.0, 118.0, 120.0, 119.0, 138.0, 115.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 159.0, 161.0, 161.0, 163.0, 162.0, 162.0, 159.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 112.0, 75.0, 76.0, 77.0, 75.0, 91.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 112.0, 86.0, 83.0, 82.0, 99.0, 82.0, 82.0, 111.0], "value": 90.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 112.0, 112.0], "value": 112.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 147.0, 149.0, 148.0, 146.0, 147.0, 147.0, 146.0, 146.0], "value": 147.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 57.0, 57.0, 62.0, 59.0, 56.0, 55.0, 57.0], "value": 57.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 53.0, 52.0, 53.0, 56.0, 54.0, 54.0, 53.0], "value": 53.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 77.0, 85.0, 75.0, 82.0, 77.0, 75.0, 83.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 121.0, 120.0, 121.0, 131.0, 130.0, 119.0, 117.0, 162.0, 126.0], "value": 127.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 174.0, 167.0, 167.0, 165.0, 167.0, 165.0, 164.0, 169.0, 169.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 65.0, 59.0, 59.0, 59.0, 58.0, 59.0, 58.0, 58.0], "value": 59.7, "unit": "score"}]}]} 12:43:26 INFO - Return code: 0 12:43:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:43:26 ERROR - # TBPL SUCCESS # 12:43:26 INFO - Running post-action listener: _resource_record_post_action 12:43:26 INFO - [mozharness: 2017-04-10 19:43:26.326118Z] Finished run-tests step (success) 12:43:26 INFO - Running post-run listener: _resource_record_post_run 12:43:26 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 4096; Write bytes: 363954176; Read time: 8; Write time: 388812 12:43:26 INFO - TinderboxPrint: CPU usage
13.1% 12:43:26 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 12:43:26 INFO - TinderboxPrint: I/O write bytes / time
363,954,176 / 388,812 12:43:26 INFO - TinderboxPrint: CPU idle
8,277.5 (86.9%) 12:43:26 INFO - TinderboxPrint: CPU user
1,201.3 (12.6%) 12:43:26 INFO - TinderboxPrint: Swap in / out
0 / 0 12:43:26 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112103424; Read time: 0; Write time: 120396 12:43:26 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 251465728; Read time: 8; Write time: 268392 12:43:26 INFO - Running post-run listener: _upload_blobber_files 12:43:26 INFO - Blob upload gear active. 12:43:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:43:26 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 12:43:26 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'] 12:43:26 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 12:43:27 INFO - (blobuploader) - INFO - Open directory for files ... 12:43:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:43:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:43:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:28 INFO - (blobuploader) - INFO - Done attempting. 12:43:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:43:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:43:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:32 INFO - (blobuploader) - INFO - Done attempting. 12:43:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:43:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:43:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:32 INFO - (blobuploader) - INFO - Done attempting. 12:43:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:43:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:43:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:43:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:43:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:43:33 INFO - (blobuploader) - INFO - Done attempting. 12:43:33 INFO - (blobuploader) - INFO - Iteration through files over. 12:43:33 INFO - Return code: 0 12:43:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:43:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:43:33 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d24069c0f4bfe119543f485a08c7d58cf76b957980a33c04ad692c9cce0d1f62f3b9c9a99aa247994ff2467394e859fe4ac12937372b335bd267f44f72ad97c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7db1ebebc6d44a477c5fb0d9d8432290ba2e5c13e29ad9033333b6c02ff6d11c450cb285b193d644b92091c3a5a427d41338c430a1cc7dd43308e6e2daf81529", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9aa13cb8853b4992f2f4b3f5b595c748e7759ed526a2a360dbfb7a0339254e3c6f5d0bdc791c9754be068f135af7194799a9afca251d2c657b97a7333d27bdb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9e2b520b6a616254ae4d0f665ef8ccd5ad7b836d1298858f4cd5c45dbb9fe7f3b68084486eefd2604193f49a821cfc746e5a69ff1a96ccfa39e14b4076eccd0"} 12:43:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:43:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:43:33 INFO - Contents: 12:43:33 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d24069c0f4bfe119543f485a08c7d58cf76b957980a33c04ad692c9cce0d1f62f3b9c9a99aa247994ff2467394e859fe4ac12937372b335bd267f44f72ad97c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7db1ebebc6d44a477c5fb0d9d8432290ba2e5c13e29ad9033333b6c02ff6d11c450cb285b193d644b92091c3a5a427d41338c430a1cc7dd43308e6e2daf81529", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9aa13cb8853b4992f2f4b3f5b595c748e7759ed526a2a360dbfb7a0339254e3c6f5d0bdc791c9754be068f135af7194799a9afca251d2c657b97a7333d27bdb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9e2b520b6a616254ae4d0f665ef8ccd5ad7b836d1298858f4cd5c45dbb9fe7f3b68084486eefd2604193f49a821cfc746e5a69ff1a96ccfa39e14b4076eccd0"} 12:43:33 INFO - Running post-run listener: copy_logs_to_upload_dir 12:43:33 INFO - Copying logs to upload dir... 12:43:33 INFO - mkdir: /builds/slave/test/build/upload/logs 12:43:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.938844 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-04-10 12:43:33.209008) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 12:43:33.212407) ========= 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=df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d24069c0f4bfe119543f485a08c7d58cf76b957980a33c04ad692c9cce0d1f62f3b9c9a99aa247994ff2467394e859fe4ac12937372b335bd267f44f72ad97c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7db1ebebc6d44a477c5fb0d9d8432290ba2e5c13e29ad9033333b6c02ff6d11c450cb285b193d644b92091c3a5a427d41338c430a1cc7dd43308e6e2daf81529", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9aa13cb8853b4992f2f4b3f5b595c748e7759ed526a2a360dbfb7a0339254e3c6f5d0bdc791c9754be068f135af7194799a9afca251d2c657b97a7333d27bdb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9e2b520b6a616254ae4d0f665ef8ccd5ad7b836d1298858f4cd5c45dbb9fe7f3b68084486eefd2604193f49a821cfc746e5a69ff1a96ccfa39e14b4076eccd0"} build_url:https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011294 build_url: 'https://queue.taskcluster.net/v1/task/eesq6nErSJ-ZgL1_ugp3sw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d24069c0f4bfe119543f485a08c7d58cf76b957980a33c04ad692c9cce0d1f62f3b9c9a99aa247994ff2467394e859fe4ac12937372b335bd267f44f72ad97c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7db1ebebc6d44a477c5fb0d9d8432290ba2e5c13e29ad9033333b6c02ff6d11c450cb285b193d644b92091c3a5a427d41338c430a1cc7dd43308e6e2daf81529", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9aa13cb8853b4992f2f4b3f5b595c748e7759ed526a2a360dbfb7a0339254e3c6f5d0bdc791c9754be068f135af7194799a9afca251d2c657b97a7333d27bdb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d9e2b520b6a616254ae4d0f665ef8ccd5ad7b836d1298858f4cd5c45dbb9fe7f3b68084486eefd2604193f49a821cfc746e5a69ff1a96ccfa39e14b4076eccd0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-10 12:43:33.237633) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:43:33.237937) ========= 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=df17445c3590ff7897617a0e00000225-1491852158.65111-1553037649 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004414 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-10 12:43:33.323873) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 12:43:33.324207) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-10 12:43:33.324508) ========= ========= Total master_lag: 0.17 =========