builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-082 starttime: 1491899975.83 results: success (0) revision: 7b0c6d30f63a80f1d67e37d991a807490abd06f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:35.829508) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:35.829966) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:35.830236) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:35.845429) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:35.845719) ========= 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=51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-11 01:39:36-- 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% 89.0M=0s 2017-04-11 01:39:37 (89.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.087819 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:35.948580) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:35.948966) ========= 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=51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016163 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:35.998801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:35.999182) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7b0c6d30f63a80f1d67e37d991a807490abd06f9 --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 7b0c6d30f63a80f1d67e37d991a807490abd06f9 --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=51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-11 01:39:37,150 truncating revision to first 12 chars 2017-04-11 01:39:37,150 Setting DEBUG logging. 2017-04-11 01:39:37,150 attempt 1/10 2017-04-11 01:39:37,151 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7b0c6d30f63a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-11 01:39:37,591 unpacking tar archive at: mozilla-aurora-7b0c6d30f63a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.774928 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:36.787515) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:36.787843) ========= script_repo_revision: 7b0c6d30f63a80f1d67e37d991a807490abd06f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:36.788236) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:36.788559) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-11 01:39:36.798997) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-04-11 01:39:36.799277) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:39:38 INFO - MultiFileLogger online at 20170411 01:39:38 in /builds/slave/test 01:39:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 01:39:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:39:38 INFO - {'append_to_log': False, 01:39:38 INFO - 'base_work_dir': '/builds/slave/test', 01:39:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 01:39:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:39:38 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 01:39:38 INFO - 'buildbot_json_path': 'buildprops.json', 01:39:38 INFO - 'config_files': ('talos/linux_config.py',), 01:39:38 INFO - 'default_actions': ('clobber', 01:39:38 INFO - 'read-buildbot-config', 01:39:38 INFO - 'download-and-extract', 01:39:38 INFO - 'populate-webroot', 01:39:38 INFO - 'create-virtualenv', 01:39:38 INFO - 'install', 01:39:38 INFO - 'run-tests'), 01:39:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:39:38 INFO - 'download_minidump_stackwalk': True, 01:39:38 INFO - 'download_symbols': 'ondemand', 01:39:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:39:38 INFO - 'tooltool.py': '/tools/tooltool.py', 01:39:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:39:38 INFO - '/tools/misc-python/virtualenv.py')}, 01:39:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:39:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:39:38 INFO - 'gecko_profile': False, 01:39:38 INFO - 'gecko_profile_interval': 0, 01:39:38 INFO - 'installer_path': 'installer.exe', 01:39:38 INFO - 'log_level': 'info', 01:39:38 INFO - 'log_name': 'talos', 01:39:38 INFO - 'log_to_console': True, 01:39:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:39:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:39:38 INFO - 'opt_config_files': (), 01:39:38 INFO - 'pip_index': False, 01:39:38 INFO - 'suite': 'dromaeojs', 01:39:38 INFO - 'system_bits': '32', 01:39:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:39:38 INFO - 'title': 'talos-linux64-ix-082', 01:39:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:39:38 INFO - 'use_talos_json': True, 01:39:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:39:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:39:38 INFO - 'work_dir': 'build'} 01:39:38 INFO - [mozharness: 2017-04-11 08:39:38.045706Z] Running clobber step. 01:39:38 INFO - Running pre-action listener: _resource_record_pre_action 01:39:38 INFO - Running main action method: clobber 01:39:38 INFO - rmtree: /builds/slave/test/build 01:39:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:39:38 INFO - Running post-action listener: _resource_record_post_action 01:39:38 INFO - [mozharness: 2017-04-11 08:39:38.988342Z] Finished clobber step (success) 01:39:38 INFO - [mozharness: 2017-04-11 08:39:38.988442Z] Running read-buildbot-config step. 01:39:38 INFO - Running pre-action listener: _resource_record_pre_action 01:39:38 INFO - Running main action method: read_buildbot_config 01:39:38 INFO - Using buildbot properties: 01:39:38 INFO - { 01:39:38 INFO - "project": "", 01:39:38 INFO - "product": "firefox", 01:39:38 INFO - "who": "nobody@mozilla.org", 01:39:38 INFO - "installer_path": "public/build/target.tar.bz2", 01:39:38 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 01:39:38 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora", 01:39:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 01:39:38 INFO - "stage_platform": "linux64", 01:39:38 INFO - "basedir": "/builds/slave/test", 01:39:38 INFO - "buildnumber": 127, 01:39:38 INFO - "platform": "ubuntu64_hw", 01:39:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 01:39:38 INFO - "slavebuilddir": "test", 01:39:38 INFO - "taskId": "MVB3GBXXQOSwj-qzfi5Epg", 01:39:38 INFO - "branch": "mozilla-aurora", 01:39:38 INFO - "script_repo_revision": "production", 01:39:38 INFO - "revision": "7b0c6d30f63a80f1d67e37d991a807490abd06f9", 01:39:38 INFO - "slavename": "talos-linux64-ix-082", 01:39:38 INFO - "repo_path": "releases/mozilla-aurora" 01:39:38 INFO - } 01:39:38 INFO - Finding installer, test and symbols from parent task. 01:39:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MVB3GBXXQOSwj-qzfi5Epg'}, attempt #1 01:39:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MVB3GBXXQOSwj-qzfi5Epg'}, attempt #1 01:39:39 INFO - Task dependencies: GWCKor95R4G6_k_GxXzP5Q 01:39:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q'}, attempt #1 01:39:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.tar.bz2 01:39:40 INFO - Set test_url: https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/test_packages.json 01:39:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.crashreporter-symbols.zip 01:39:40 INFO - Running post-action listener: _resource_record_post_action 01:39:40 INFO - [mozharness: 2017-04-11 08:39:40.364404Z] Finished read-buildbot-config step (success) 01:39:40 INFO - [mozharness: 2017-04-11 08:39:40.364623Z] Running download-and-extract step. 01:39:40 INFO - Running pre-action listener: _resource_record_pre_action 01:39:40 INFO - Running main action method: download_and_extract 01:39:40 INFO - mkdir: /builds/slave/test/build/tests 01:39:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:39:40 INFO - trying https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.test_packages.json 01:39:40 INFO - Downloading https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:39:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:39:40 INFO - Downloaded 896 bytes. 01:39:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:39:40 INFO - Using the following test package requirements: 01:39:40 INFO - {u'common': [u'target.common.tests.zip'], 01:39:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:39:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:39:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:39:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:39:40 INFO - u'mozbase': [u'target.common.tests.zip'], 01:39:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:39:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:39:40 INFO - u'web-platform': [u'target.common.tests.zip', 01:39:40 INFO - u'target.web-platform.tests.zip'], 01:39:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:39:40 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:39:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.common.tests.zip 01:39:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:39:40 INFO - Fetch https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.common.tests.zip into memory 01:39:42 INFO - Expected file size: 59547702 01:39:42 INFO - Obtained file size: 59547702 01:39:51 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:39:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.common.tests.zip 01:39:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:39:51 INFO - Fetch https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.common.tests.zip into memory 01:39:52 INFO - Expected file size: 59547702 01:39:52 INFO - Obtained file size: 59547702 01:40:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.talos.tests.zip 01:40:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:40:03 INFO - Fetch https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.talos.tests.zip into memory 01:40:04 INFO - Expected file size: 14404424 01:40:04 INFO - Obtained file size: 14404424 01:40:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:04 INFO - trying https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.tar.bz2 01:40:04 INFO - Downloading https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:40:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:40:05 INFO - Downloaded 66775200 bytes. 01:40:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.tar.bz2 01:40:05 INFO - mkdir: /builds/slave/test/properties 01:40:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:40:05 INFO - Writing to file /builds/slave/test/properties/build_url 01:40:05 INFO - Contents: 01:40:05 INFO - build_url:https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.tar.bz2 01:40:05 INFO - Running post-action listener: _resource_record_post_action 01:40:05 INFO - Running post-action listener: set_extra_try_arguments 01:40:05 INFO - [mozharness: 2017-04-11 08:40:05.697168Z] Finished download-and-extract step (success) 01:40:05 INFO - [mozharness: 2017-04-11 08:40:05.697265Z] Running populate-webroot step. 01:40:05 INFO - Running pre-action listener: _resource_record_pre_action 01:40:05 INFO - Running main action method: populate_webroot 01:40:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:40:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:40:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:40:05 INFO - '--tppagecycles', 01:40:05 INFO - '7'], 01:40:05 INFO - 'tests': ['tsvgm']}}, 01:40:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:40:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:40:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:40:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:40:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:40:05 INFO - 'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:40:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:40:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:40:05 INFO - 'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['damp', 'tps']}, 01:40:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:40:05 INFO - 'tests': ['damp', 'tps']}, 01:40:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['dromaeo_dom']}, 01:40:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:40:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['basic_compositor_video']}, 01:40:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:40:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['a11yr', 01:40:05 INFO - 'ts_paint', 01:40:05 INFO - 'tpaint', 01:40:05 INFO - 'sessionrestore', 01:40:05 INFO - 'sessionrestore_no_auto_restore', 01:40:05 INFO - 'tabpaint']}, 01:40:05 INFO - 'other-e10s': {'tests': ['a11yr', 01:40:05 INFO - 'ts_paint', 01:40:05 INFO - 'tpaint', 01:40:05 INFO - 'sessionrestore', 01:40:05 INFO - 'sessionrestore_no_auto_restore', 01:40:05 INFO - 'tabpaint']}, 01:40:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:40:05 INFO - 'ts_paint', 01:40:05 INFO - 'tpaint', 01:40:05 INFO - 'sessionrestore', 01:40:05 INFO - 'sessionrestore_no_auto_restore', 01:40:05 INFO - 'tabpaint']}, 01:40:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:40:05 INFO - 'ts_paint', 01:40:05 INFO - 'tpaint', 01:40:05 INFO - 'sessionrestore', 01:40:05 INFO - 'sessionrestore_no_auto_restore', 01:40:05 INFO - 'tabpaint']}, 01:40:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['a11yr', 01:40:05 INFO - 'ts_paint', 01:40:05 INFO - 'tpaint', 01:40:05 INFO - 'sessionrestore', 01:40:05 INFO - 'sessionrestore_no_auto_restore', 01:40:05 INFO - 'tabpaint']}, 01:40:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['a11yr', 01:40:05 INFO - 'ts_paint', 01:40:05 INFO - 'tpaint', 01:40:05 INFO - 'sessionrestore', 01:40:05 INFO - 'sessionrestore_no_auto_restore', 01:40:05 INFO - 'tabpaint']}, 01:40:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['tsvgx', 01:40:05 INFO - 'tsvgr_opacity', 01:40:05 INFO - 'tart', 01:40:05 INFO - 'tscrollx', 01:40:05 INFO - 'cart', 01:40:05 INFO - 'tsvg_static']}, 01:40:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:40:05 INFO - 'tsvgr_opacity', 01:40:05 INFO - 'tart', 01:40:05 INFO - 'tscrollx', 01:40:05 INFO - 'cart', 01:40:05 INFO - 'tsvg_static']}, 01:40:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:40:05 INFO - 'talos_options': ['--disable-e10s'], 01:40:05 INFO - 'tests': ['tp5o']}, 01:40:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:40:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:40:05 INFO - 'talos_options': ['--disable-e10s', 01:40:05 INFO - '--xperf_path', 01:40:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:05 INFO - 'tests': ['tp5n']}, 01:40:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:40:05 INFO - 'talos_options': ['--xperf_path', 01:40:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:40:05 INFO - 'tests': ['tp5n']}}, 01:40:05 INFO - 'talos.zip': {'path': '', 01:40:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:40:05 INFO - Running post-action listener: _resource_record_post_action 01:40:05 INFO - [mozharness: 2017-04-11 08:40:05.710575Z] Finished populate-webroot step (success) 01:40:05 INFO - [mozharness: 2017-04-11 08:40:05.710666Z] Running create-virtualenv step. 01:40:05 INFO - Running pre-action listener: _resource_record_pre_action 01:40:05 INFO - Running main action method: create_virtualenv 01:40:05 INFO - Creating virtualenv /builds/slave/test/build/venv 01:40:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:40:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:40:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:40:05 INFO - Using real prefix '/usr' 01:40:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:40:06 INFO - Installing distribute.............................................................................................................................................................................................done. 01:40:08 INFO - Installing pip.................done. 01:40:08 INFO - Return code: 0 01:40:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:40:08 INFO - Reading from file tmpfile_stdout 01:40:08 INFO - Output received: 01:40:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 01:40:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:08 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 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:40:08 INFO - Using env: {'DISPLAY': ':0', 01:40:08 INFO - 'HOME': '/home/cltbld', 01:40:08 INFO - 'LANG': 'en_US.UTF-8', 01:40:08 INFO - 'LANGUAGE': 'en_US:en', 01:40:08 INFO - 'LOGNAME': 'cltbld', 01:40:08 INFO - 'MAIL': '/var/mail/cltbld', 01:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:08 INFO - 'MOZ_NO_REMOTE': '1', 01:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:08 INFO - 'NO_EM_RESTART': '1', 01:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:08 INFO - 'PWD': '/builds/slave/test', 01:40:08 INFO - 'SHELL': '/bin/bash', 01:40:08 INFO - 'SHLVL': '1', 01:40:08 INFO - 'TERM': 'linux', 01:40:08 INFO - 'TMOUT': '86400', 01:40:08 INFO - 'USER': 'cltbld', 01:40:08 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:08 INFO - '_': '/tools/buildbot/bin/python'} 01:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:40:08 INFO - Cleaning up... 01:40:08 INFO - Return code: 0 01:40:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:08 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 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:40:08 INFO - Using env: {'DISPLAY': ':0', 01:40:08 INFO - 'HOME': '/home/cltbld', 01:40:08 INFO - 'LANG': 'en_US.UTF-8', 01:40:08 INFO - 'LANGUAGE': 'en_US:en', 01:40:08 INFO - 'LOGNAME': 'cltbld', 01:40:08 INFO - 'MAIL': '/var/mail/cltbld', 01:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:08 INFO - 'MOZ_NO_REMOTE': '1', 01:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:08 INFO - 'NO_EM_RESTART': '1', 01:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:08 INFO - 'PWD': '/builds/slave/test', 01:40:08 INFO - 'SHELL': '/bin/bash', 01:40:08 INFO - 'SHLVL': '1', 01:40:08 INFO - 'TERM': 'linux', 01:40:08 INFO - 'TMOUT': '86400', 01:40:08 INFO - 'USER': 'cltbld', 01:40:08 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:08 INFO - '_': '/tools/buildbot/bin/python'} 01:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:08 INFO - Downloading/unpacking psutil>=3.1.1 01:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:40:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:11 INFO - Installing collected packages: psutil 01:40:11 INFO - Running setup.py install for psutil 01:40:11 INFO - building 'psutil._psutil_linux' extension 01:40:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 01:40:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 01:40:11 INFO - building 'psutil._psutil_posix' extension 01:40:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 01:40:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 01:40:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:40:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:40:11 INFO - Successfully installed psutil 01:40:11 INFO - Cleaning up... 01:40:11 INFO - Return code: 0 01:40:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:40: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 mozsystemmonitor==0.3 01:40:11 INFO - Using env: {'DISPLAY': ':0', 01:40:11 INFO - 'HOME': '/home/cltbld', 01:40:11 INFO - 'LANG': 'en_US.UTF-8', 01:40:11 INFO - 'LANGUAGE': 'en_US:en', 01:40:11 INFO - 'LOGNAME': 'cltbld', 01:40:11 INFO - 'MAIL': '/var/mail/cltbld', 01:40:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:11 INFO - 'MOZ_NO_REMOTE': '1', 01:40:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:11 INFO - 'NO_EM_RESTART': '1', 01:40:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:11 INFO - 'PWD': '/builds/slave/test', 01:40:11 INFO - 'SHELL': '/bin/bash', 01:40:11 INFO - 'SHLVL': '1', 01:40:11 INFO - 'TERM': 'linux', 01:40:11 INFO - 'TMOUT': '86400', 01:40:11 INFO - 'USER': 'cltbld', 01:40:11 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:11 INFO - '_': '/tools/buildbot/bin/python'} 01:40:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:40:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:40:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:40:13 INFO - Installing collected packages: mozsystemmonitor 01:40:13 INFO - Running setup.py install for mozsystemmonitor 01:40:14 INFO - Successfully installed mozsystemmonitor 01:40:14 INFO - Cleaning up... 01:40:14 INFO - Return code: 0 01:40:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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', '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 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:40: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 jsonschema==2.5.1 01:40:14 INFO - Using env: {'DISPLAY': ':0', 01:40:14 INFO - 'HOME': '/home/cltbld', 01:40:14 INFO - 'LANG': 'en_US.UTF-8', 01:40:14 INFO - 'LANGUAGE': 'en_US:en', 01:40:14 INFO - 'LOGNAME': 'cltbld', 01:40:14 INFO - 'MAIL': '/var/mail/cltbld', 01:40:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:14 INFO - 'MOZ_NO_REMOTE': '1', 01:40:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:14 INFO - 'NO_EM_RESTART': '1', 01:40:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:14 INFO - 'PWD': '/builds/slave/test', 01:40:14 INFO - 'SHELL': '/bin/bash', 01:40:14 INFO - 'SHLVL': '1', 01:40:14 INFO - 'TERM': 'linux', 01:40:14 INFO - 'TMOUT': '86400', 01:40:14 INFO - 'USER': 'cltbld', 01:40:14 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:14 INFO - '_': '/tools/buildbot/bin/python'} 01:40:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:14 INFO - Downloading/unpacking jsonschema==2.5.1 01:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:40:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:16 INFO - Downloading functools32-3.2.3-2.tar.gz 01:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:40:16 INFO - warning: no files found matching '*.txt' 01:40:16 INFO - no previously-included directories found matching 'build' 01:40:16 INFO - no previously-included directories found matching 'dist' 01:40:16 INFO - no previously-included directories found matching '.git*' 01:40:16 INFO - Installing collected packages: jsonschema, functools32 01:40:16 INFO - Running setup.py install for functools32 01:40:16 INFO - warning: no files found matching '*.txt' 01:40:16 INFO - no previously-included directories found matching 'build' 01:40:16 INFO - no previously-included directories found matching 'dist' 01:40:16 INFO - no previously-included directories found matching '.git*' 01:40:16 INFO - Successfully installed jsonschema functools32 01:40:16 INFO - Cleaning up... 01:40:16 INFO - Return code: 0 01:40:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:16 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 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:40:16 INFO - Using env: {'DISPLAY': ':0', 01:40:16 INFO - 'HOME': '/home/cltbld', 01:40:16 INFO - 'LANG': 'en_US.UTF-8', 01:40:16 INFO - 'LANGUAGE': 'en_US:en', 01:40:16 INFO - 'LOGNAME': 'cltbld', 01:40:16 INFO - 'MAIL': '/var/mail/cltbld', 01:40:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:16 INFO - 'MOZ_NO_REMOTE': '1', 01:40:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:16 INFO - 'NO_EM_RESTART': '1', 01:40:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:16 INFO - 'PWD': '/builds/slave/test', 01:40:16 INFO - 'SHELL': '/bin/bash', 01:40:16 INFO - 'SHLVL': '1', 01:40:16 INFO - 'TERM': 'linux', 01:40:16 INFO - 'TMOUT': '86400', 01:40:16 INFO - 'USER': 'cltbld', 01:40:16 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:16 INFO - '_': '/tools/buildbot/bin/python'} 01:40:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:40:16 INFO - Cleaning up... 01:40:17 INFO - Return code: 0 01:40:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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', '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 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:40: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 blobuploader==1.2.4 01:40:17 INFO - Using env: {'DISPLAY': ':0', 01:40:17 INFO - 'HOME': '/home/cltbld', 01:40:17 INFO - 'LANG': 'en_US.UTF-8', 01:40:17 INFO - 'LANGUAGE': 'en_US:en', 01:40:17 INFO - 'LOGNAME': 'cltbld', 01:40:17 INFO - 'MAIL': '/var/mail/cltbld', 01:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:17 INFO - 'MOZ_NO_REMOTE': '1', 01:40:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:17 INFO - 'NO_EM_RESTART': '1', 01:40:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:17 INFO - 'PWD': '/builds/slave/test', 01:40:17 INFO - 'SHELL': '/bin/bash', 01:40:17 INFO - 'SHLVL': '1', 01:40:17 INFO - 'TERM': 'linux', 01:40:17 INFO - 'TMOUT': '86400', 01:40:17 INFO - 'USER': 'cltbld', 01:40:17 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:17 INFO - '_': '/tools/buildbot/bin/python'} 01:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:17 INFO - Downloading/unpacking blobuploader==1.2.4 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40:19 INFO - Downloading blobuploader-1.2.4.tar.gz 01:40:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:40:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:40:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40: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 01:40:19 INFO - Downloading docopt-0.6.1.tar.gz 01:40:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:40:20 INFO - Installing collected packages: blobuploader, requests, docopt 01:40:20 INFO - Running setup.py install for blobuploader 01:40:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:40:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:40:20 INFO - Running setup.py install for requests 01:40:20 INFO - Running setup.py install for docopt 01:40:20 INFO - Successfully installed blobuploader requests docopt 01:40:20 INFO - Cleaning up... 01:40:20 INFO - Return code: 0 01:40:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:20 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 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:40:20 INFO - Using env: {'DISPLAY': ':0', 01:40:20 INFO - 'HOME': '/home/cltbld', 01:40:20 INFO - 'LANG': 'en_US.UTF-8', 01:40:20 INFO - 'LANGUAGE': 'en_US:en', 01:40:20 INFO - 'LOGNAME': 'cltbld', 01:40:20 INFO - 'MAIL': '/var/mail/cltbld', 01:40:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:20 INFO - 'MOZ_NO_REMOTE': '1', 01:40:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:20 INFO - 'NO_EM_RESTART': '1', 01:40:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:20 INFO - 'PWD': '/builds/slave/test', 01:40:20 INFO - 'SHELL': '/bin/bash', 01:40:20 INFO - 'SHLVL': '1', 01:40:20 INFO - 'TERM': 'linux', 01:40:20 INFO - 'TMOUT': '86400', 01:40:20 INFO - 'USER': 'cltbld', 01:40:20 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:20 INFO - '_': '/tools/buildbot/bin/python'} 01:40:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:40:20 INFO - Running setup.py (path:/tmp/pip-ZcGvsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:40:20 INFO - Running setup.py (path:/tmp/pip-C_hz03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:40:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:40:20 INFO - Running setup.py (path:/tmp/pip-EfR50r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:40:21 INFO - Running setup.py (path:/tmp/pip-fNf6Ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:40:21 INFO - Running setup.py (path:/tmp/pip-3I9qfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:40:21 INFO - Running setup.py (path:/tmp/pip-p0BUYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:40:21 INFO - Running setup.py (path:/tmp/pip-ArTd3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:40:21 INFO - Running setup.py (path:/tmp/pip-XnAlo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:40:21 INFO - Running setup.py (path:/tmp/pip-ldHpyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:40:21 INFO - Running setup.py (path:/tmp/pip-8Ryn_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:40:21 INFO - Running setup.py (path:/tmp/pip-VGG0Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:40:21 INFO - Running setup.py (path:/tmp/pip-opnG8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:40:21 INFO - Running setup.py (path:/tmp/pip-99tN6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:40:21 INFO - Running setup.py (path:/tmp/pip-TpIZkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:21 INFO - Running setup.py (path:/tmp/pip-bc0Qvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:40:21 INFO - Running setup.py (path:/tmp/pip-2DfDmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:40:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:40:22 INFO - Running setup.py (path:/tmp/pip-WdtFTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:40:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:40:22 INFO - Running setup.py install for manifestparser 01:40:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:40:22 INFO - Running setup.py install for mozcrash 01:40:22 INFO - Running setup.py install for mozdebug 01:40:22 INFO - Running setup.py install for mozdevice 01:40:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:40:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:40:22 INFO - Running setup.py install for mozfile 01:40:22 INFO - Running setup.py install for mozhttpd 01:40:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:40:22 INFO - Running setup.py install for mozinfo 01:40:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:40:22 INFO - Running setup.py install for mozInstall 01:40:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:40:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:40:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:40:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:40:22 INFO - Running setup.py install for mozleak 01:40:22 INFO - Running setup.py install for mozlog 01:40:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:40:23 INFO - Running setup.py install for moznetwork 01:40:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:40:23 INFO - Running setup.py install for mozprocess 01:40:23 INFO - Running setup.py install for mozprofile 01:40:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:40:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:40:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:40:23 INFO - Running setup.py install for mozrunner 01:40:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:40:23 INFO - Running setup.py install for mozscreenshot 01:40:23 INFO - Running setup.py install for moztest 01:40:23 INFO - Running setup.py install for mozversion 01:40:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:40:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:40:23 INFO - Cleaning up... 01:40:23 INFO - Return code: 0 01:40:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40: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')]} 01:40:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40: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')]} 01:40:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:23 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 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:40:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:40:23 INFO - Using env: {'DISPLAY': ':0', 01:40:23 INFO - 'HOME': '/home/cltbld', 01:40:23 INFO - 'LANG': 'en_US.UTF-8', 01:40:23 INFO - 'LANGUAGE': 'en_US:en', 01:40:23 INFO - 'LOGNAME': 'cltbld', 01:40:23 INFO - 'MAIL': '/var/mail/cltbld', 01:40:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:23 INFO - 'MOZ_NO_REMOTE': '1', 01:40:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:23 INFO - 'NO_EM_RESTART': '1', 01:40:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:23 INFO - 'PWD': '/builds/slave/test', 01:40:23 INFO - 'SHELL': '/bin/bash', 01:40:23 INFO - 'SHLVL': '1', 01:40:23 INFO - 'TERM': 'linux', 01:40:23 INFO - 'TMOUT': '86400', 01:40:23 INFO - 'USER': 'cltbld', 01:40:23 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:23 INFO - '_': '/tools/buildbot/bin/python'} 01:40:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:40:24 INFO - Running setup.py (path:/tmp/pip-2_ZVio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:40:24 INFO - Running setup.py (path:/tmp/pip-dIluY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:40:24 INFO - Running setup.py (path:/tmp/pip-Y7VORm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:40:24 INFO - Running setup.py (path:/tmp/pip-EcyrMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:40:24 INFO - Running setup.py (path:/tmp/pip-zvOlQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:40:24 INFO - Running setup.py (path:/tmp/pip-nUCa38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:40:24 INFO - Running setup.py (path:/tmp/pip-N0Aqgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:40:24 INFO - Running setup.py (path:/tmp/pip-RkuhZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:40:24 INFO - Running setup.py (path:/tmp/pip-hIlZfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:40:24 INFO - Running setup.py (path:/tmp/pip-onuigD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:40:24 INFO - Running setup.py (path:/tmp/pip-F7f8DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:40:24 INFO - Running setup.py (path:/tmp/pip-FXD39J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:40:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:40:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:40:25 INFO - Running setup.py (path:/tmp/pip-oeP9xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:40:25 INFO - Running setup.py (path:/tmp/pip-BERIY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:25 INFO - Running setup.py (path:/tmp/pip-xlwFy8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:40:25 INFO - Running setup.py (path:/tmp/pip-j3GFyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:40:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:40:25 INFO - Running setup.py (path:/tmp/pip-4daLTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:40:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:40:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:40:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:40:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:40:27 INFO - Downloading blessings-1.6.tar.gz 01:40:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:40:27 INFO - Installing collected packages: blessings 01:40:27 INFO - Running setup.py install for blessings 01:40:27 INFO - Successfully installed blessings 01:40:27 INFO - Cleaning up... 01:40:27 INFO - Return code: 0 01:40:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:40:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:40:27 INFO - Reading from file tmpfile_stdout 01:40:27 INFO - Current package versions: 01:40:27 INFO - argparse == 1.2.1 01:40:27 INFO - blessings == 1.6 01:40:27 INFO - blobuploader == 1.2.4 01:40:27 INFO - docopt == 0.6.1 01:40:27 INFO - functools32 == 3.2.3-2 01:40:27 INFO - jsonschema == 2.5.1 01:40:27 INFO - manifestparser == 1.1 01:40:27 INFO - mozInstall == 1.12 01:40:27 INFO - mozcrash == 1.0 01:40:27 INFO - mozdebug == 0.1 01:40:27 INFO - mozdevice == 0.49 01:40:27 INFO - mozfile == 1.2 01:40:27 INFO - mozhttpd == 0.7 01:40:27 INFO - mozinfo == 0.9 01:40:27 INFO - mozleak == 0.1 01:40:27 INFO - mozlog == 3.4 01:40:27 INFO - moznetwork == 0.27 01:40:27 INFO - mozprocess == 0.25 01:40:27 INFO - mozprofile == 0.28 01:40:27 INFO - mozrunner == 6.13 01:40:27 INFO - mozscreenshot == 0.1 01:40:27 INFO - mozsystemmonitor == 0.3 01:40:27 INFO - moztest == 0.8 01:40:27 INFO - mozversion == 1.4 01:40:27 INFO - psutil == 3.1.1 01:40:27 INFO - requests == 1.2.3 01:40:27 INFO - wsgiref == 0.1.2 01:40:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:27 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 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:40:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:40:27 INFO - Using env: {'DISPLAY': ':0', 01:40:27 INFO - 'HOME': '/home/cltbld', 01:40:27 INFO - 'LANG': 'en_US.UTF-8', 01:40:27 INFO - 'LANGUAGE': 'en_US:en', 01:40:27 INFO - 'LOGNAME': 'cltbld', 01:40:27 INFO - 'MAIL': '/var/mail/cltbld', 01:40:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:27 INFO - 'MOZ_NO_REMOTE': '1', 01:40:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:27 INFO - 'NO_EM_RESTART': '1', 01:40:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:27 INFO - 'PWD': '/builds/slave/test', 01:40:27 INFO - 'SHELL': '/bin/bash', 01:40:27 INFO - 'SHLVL': '1', 01:40:27 INFO - 'TERM': 'linux', 01:40:27 INFO - 'TMOUT': '86400', 01:40:27 INFO - 'USER': 'cltbld', 01:40:27 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:27 INFO - '_': '/tools/buildbot/bin/python'} 01:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:40:28 INFO - Cleaning up... 01:40:28 INFO - Return code: 0 01:40:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:40:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:40:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:40:28 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 0x23901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23cea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x262e310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, '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': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:40:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:40:28 INFO - Using env: {'DISPLAY': ':0', 01:40:28 INFO - 'HOME': '/home/cltbld', 01:40:28 INFO - 'LANG': 'en_US.UTF-8', 01:40:28 INFO - 'LANGUAGE': 'en_US:en', 01:40:28 INFO - 'LOGNAME': 'cltbld', 01:40:28 INFO - 'MAIL': '/var/mail/cltbld', 01:40:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:28 INFO - 'MOZ_NO_REMOTE': '1', 01:40:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:28 INFO - 'NO_EM_RESTART': '1', 01:40:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:28 INFO - 'PWD': '/builds/slave/test', 01:40:28 INFO - 'SHELL': '/bin/bash', 01:40:28 INFO - 'SHLVL': '1', 01:40:28 INFO - 'TERM': 'linux', 01:40:28 INFO - 'TMOUT': '86400', 01:40:28 INFO - 'USER': 'cltbld', 01:40:28 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:28 INFO - '_': '/tools/buildbot/bin/python'} 01:40:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:40:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:40:28 INFO - Cleaning up... 01:40:28 INFO - Return code: 0 01:40:28 INFO - Running post-action listener: _resource_record_post_action 01:40:28 INFO - Running post-action listener: _start_resource_monitoring 01:40:28 INFO - Starting resource monitoring. 01:40:28 INFO - [mozharness: 2017-04-11 08:40:28.342153Z] Finished create-virtualenv step (success) 01:40:28 INFO - [mozharness: 2017-04-11 08:40:28.342664Z] Running install step. 01:40:28 INFO - Running pre-action listener: _resource_record_pre_action 01:40:28 INFO - Running main action method: install 01:40:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:40:28 INFO - Reading from file tmpfile_stdout 01:40:28 INFO - Detecting whether we're running mozinstall >=1.0... 01:40:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:40:28 INFO - Reading from file tmpfile_stdout 01:40:28 INFO - Output received: 01:40:28 INFO - Usage: mozinstall [options] installer 01:40:28 INFO - Options: 01:40:28 INFO - -h, --help show this help message and exit 01:40:28 INFO - -d DEST, --destination=DEST 01:40:28 INFO - Directory to install application into. [default: 01:40:28 INFO - "/builds/slave/test"] 01:40:28 INFO - --app=APP Application being installed. [default: firefox] 01:40:28 INFO - mkdir: /builds/slave/test/build/application 01:40:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:40:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:40:45 INFO - Reading from file tmpfile_stdout 01:40:45 INFO - Output received: 01:40:45 INFO - /builds/slave/test/build/application/firefox/firefox 01:40:45 INFO - Running post-action listener: _resource_record_post_action 01:40:45 INFO - [mozharness: 2017-04-11 08:40:45.226562Z] Finished install step (success) 01:40:45 INFO - [mozharness: 2017-04-11 08:40:45.226833Z] Running run-tests step. 01:40:45 INFO - Running pre-action listener: _resource_record_pre_action 01:40:45 INFO - Running main action method: run_tests 01:40:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:40:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:40:45 INFO - Python 2.7.3 01:40:45 INFO - Return code: 0 01:40:45 INFO - grabbing minidump binary from tooltool 01:40:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:40:45 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 0x262b400>, '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 0x262c3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262c840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:40:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:40:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 01:40:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:40:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:40:45 INFO - Return code: 0 01:40:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:40:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:40:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:40:45 INFO - ENV: RUST_BACKTRACE is now 1 01:40:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:40:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:40:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:40:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-082 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:40:45 INFO - Using env: {'DISPLAY': ':0', 01:40:45 INFO - 'HOME': '/home/cltbld', 01:40:45 INFO - 'LANG': 'en_US.UTF-8', 01:40:45 INFO - 'LANGUAGE': 'en_US:en', 01:40:45 INFO - 'LOGNAME': 'cltbld', 01:40:45 INFO - 'MAIL': '/var/mail/cltbld', 01:40:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:40:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:40:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:40:45 INFO - 'MOZ_NO_REMOTE': '1', 01:40:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:40:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:40:45 INFO - 'NO_EM_RESTART': '1', 01:40:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:40:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:40:45 INFO - 'PWD': '/builds/slave/test', 01:40:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:40:45 INFO - 'RUST_BACKTRACE': '1', 01:40:45 INFO - 'SHELL': '/bin/bash', 01:40:45 INFO - 'SHLVL': '1', 01:40:45 INFO - 'TERM': 'linux', 01:40:45 INFO - 'TMOUT': '86400', 01:40:45 INFO - 'USER': 'cltbld', 01:40:45 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965', 01:40:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:40:45 INFO - '_': '/tools/buildbot/bin/python'} 01:40:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:40:45 INFO - mozversion application_buildid: 20170411074804 01:40:45 INFO - mozversion application_changeset: 7b0c6d30f63a80f1d67e37d991a807490abd06f9 01:40:45 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:40:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:40:45 INFO - mozversion application_name: Firefox 01:40:45 INFO - mozversion application_remotingname: firefox-dev 01:40:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:40:45 INFO - mozversion application_vendor: Mozilla 01:40:45 INFO - mozversion application_version: 54.0a2 01:40:45 INFO - mozversion platform_buildid: 20170411074804 01:40:45 INFO - mozversion platform_changeset: 7b0c6d30f63a80f1d67e37d991a807490abd06f9 01:40:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:40:45 INFO - mozversion platform_version: 54.0a2 01:40:45 INFO - using testdate: 1491900045 01:40:45 INFO - actual date: 1491900045 01:40:45 INFO - starting webserver on 'localhost:55965' 01:40:45 INFO - SUITE-START | Running 2 tests 01:40:45 INFO - TEST-START | dromaeo_css 01:40:45 INFO - operating with platform_type : linux_ 01:40:45 INFO - Initialising browser for dromaeo_css test... 01:40:45 INFO - TEST-INFO | started process 26593 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKf35jj/profile http://localhost:55965/getInfo.html) 01:40:49 INFO - PROCESS | 26593 | __metrics Screen width/height:1600/1200 01:40:49 INFO - PROCESS | 26593 | colorDepth:24 01:40:49 INFO - PROCESS | 26593 | Browser inner width/height: 1024/697 01:40:49 INFO - PROCESS | 26593 | __metrics 01:40:50 INFO - PROCESS | 26593 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:40:50 INFO - PROCESS | 26593 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:40:50 INFO - PROCESS | 26593 | console.error: PushService: 01:40:50 INFO - PROCESS | 26593 | stateChangeProcessEnqueue: Error transitioning state 01:40:50 INFO - PROCESS | 26593 | UnknownError 01:40:51 INFO - TEST-INFO | 26593: exit 0 01:40:51 INFO - Browser initialized. 01:40:51 INFO - Running cycle 1/1 for dromaeo_css test... 01:40:51 INFO - TEST-INFO | started process 26687 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKf35jj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:41:03 INFO - PROCESS | 26687 | RSS: Main: 139640832 01:41:03 INFO - PROCESS | 26687 | 01:44:04 INFO - PROCESS | 26687 | Cycle 1(1): loaded http://localhost:55965/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55965/tests/dromaeo/cssquery-ext.html) 01:44:06 INFO - PROCESS | 26687 | RSS: Main: 280338432 01:44:06 INFO - PROCESS | 26687 | 01:45:53 INFO - PROCESS | 26687 | ************************* 01:45:53 INFO - PROCESS | 26687 | A coding exception was thrown and uncaught in a Task. 01:45:53 INFO - PROCESS | 26687 | 01:45:53 ERROR - PROCESS | 26687 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:45:53 INFO - PROCESS | 26687 | Full stack: 01:45:53 INFO - PROCESS | 26687 | ************************* 01:45:53 INFO - PROCESS | 26687 | ************************* 01:45:53 INFO - PROCESS | 26687 | A coding exception was thrown and uncaught in a Task. 01:45:53 INFO - PROCESS | 26687 | 01:45:53 ERROR - PROCESS | 26687 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:45:53 INFO - PROCESS | 26687 | Full stack: 01:45:53 INFO - PROCESS | 26687 | ************************* 01:45:53 INFO - PROCESS | 26687 | ************************* 01:45:53 INFO - PROCESS | 26687 | A coding exception was thrown and uncaught in a Task. 01:45:53 INFO - PROCESS | 26687 | 01:45:53 ERROR - PROCESS | 26687 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:45:53 INFO - PROCESS | 26687 | Full stack: 01:45:53 INFO - PROCESS | 26687 | ************************* 01:45:53 INFO - PROCESS | 26687 | ************************* 01:45:53 INFO - PROCESS | 26687 | A coding exception was thrown and uncaught in a Task. 01:45:53 INFO - PROCESS | 26687 | 01:45:53 ERROR - PROCESS | 26687 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:45:53 INFO - PROCESS | 26687 | Full stack: 01:45:53 INFO - PROCESS | 26687 | ************************* 01:47:08 INFO - PROCESS | 26687 | Cycle 1(1): loaded http://localhost:55965/tests/dromaeo/cssquery-ext.html (next: http://localhost:55965/tests/dromaeo/cssquery-jquery.html) 01:47:09 INFO - PROCESS | 26687 | RSS: Main: 216211456 01:47:09 INFO - PROCESS | 26687 | 01:50:11 INFO - PROCESS | 26687 | Cycle 1(1): loaded http://localhost:55965/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55965/tests/dromaeo/cssquery-mootools.html) 01:50:11 INFO - PROCESS | 26687 | RSS: Main: 205963264 01:50:11 INFO - PROCESS | 26687 | 01:53:13 INFO - PROCESS | 26687 | Cycle 1(1): loaded http://localhost:55965/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55965/tests/dromaeo/cssquery-prototype.html) 01:53:14 INFO - PROCESS | 26687 | RSS: Main: 171126784 01:53:14 INFO - PROCESS | 26687 | 01:56:17 INFO - PROCESS | 26687 | Cycle 1(1): loaded http://localhost:55965/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55965/tests/dromaeo/cssquery-yui.html) 01:56:17 INFO - PROCESS | 26687 | RSS: Main: 176873472 01:56:17 INFO - PROCESS | 26687 | 01:59:20 INFO - PROCESS | 26687 | Cycle 1(1): loaded http://localhost:55965/tests/dromaeo/cssquery-yui.html (next: http://localhost:55965/tests/dromaeo/cssquery-dojo.html) 01:59:20 INFO - PROCESS | 26687 | RSS: Main: 270565376 01:59:20 INFO - PROCESS | 26687 | 01:59:20 INFO - PROCESS | 26687 | __start_tp_report 01:59:20 INFO - PROCESS | 26687 | _x_x_mozilla_page_load 01:59:20 INFO - PROCESS | 26687 | _x_x_mozilla_page_load_details 01:59:20 INFO - PROCESS | 26687 | |i|pagename|runs| 01:59:20 INFO - PROCESS | 26687 | |0;dojo.html;3344.347852168827;3433.793972361657;3444.6727560881723;3481.4603736420863;3495.1262184453885;5749.080147176453;5798.9130163047585;5822.1266809978415;5824.708764561776;5832.329282132556;5768.682722450274;5824.0390335594575;5826.9708651456685;5830.475257226849;5834.358220595731;5157.5358217760395;5161.8451446456575;5211.400688920785;5211.895762084756;5251.474852514756;4999.725015124167;5026.97486512567;5107.821226257063;5252.264682944372;5262.4474430184855;5203.973980130113;5209.166533354664;5227.32044834171;5227.764750586232;5249.317588713462;5239.659422137549;5239.711815850127;5246;5256.448072952361;5263.86840328991;4903.926441103386;4971.105201063807;5109.540141387293;5118.718470484122;5181.093308670967;5694;5742.885142297172;5764.048931926227;5772.30732312121;5791.681457519835;6170.969145154246;6214.65819379934;6215.316315205324;6219.253689557237;6221.813345599639;6275.623462592259;6303.3066362700065;6308.968455157741;6312.274088479838;6312.621242725451;5568.582356323252;5571.693556854371;5796.913046304308;5875.148103524965;5883.323417806964;6328.936710632926;6338.144350512714;6339.397757213057;6339.841503962346;6349.047642853517;6117.969410153009;6122.724477398528;6135.202423684982;6137.110119032715;6141.723622437001;5924.052151655715;5961.314448838352;6056.636601803818;6119.326874043852;6181.289151747516;5892.351841297454;6125.6017461851925;6134.601250918676;6158.969205154035;6160.784372546939;6022.45797878193;6033.5474839387225;6065.727042283108;6110.779634049289;6114.945045103268;4663.044075964419;4714.504976977437;4721.126591580569;4725.645576581702;4743.66794324394;4072.0634254121123;4118.135191609796;4238.618524332764;4251.043515209115;4264.722793018444;5891.410858914074;5948.881022379528;6018.187544681412;6048.032314829607;6094.603850749687;6188.071789231652;6209.130721698965;6215.471684906834;6219.968900155471;6225.159603453566;5704.3154821421695;5979.1629171915965;5987.700614969322;5993.940060599425;6030.155778190969;5115.974420127875;5187.818426355059;5230.712310822865;5265.789368425297;5268.051750684914;6541.411272985454;6575.835604109936;6576.342365763386;6576.736930522821;6594.505412094112;6188.102725104744;6190.597611155261;6241.594296370721;6255.37446255389;6264.34224406449;6139.584134579286;6215.96892015537;6403.64779937108;6451.677416129269;6565.244996825426;6123.069106197319;6128.258691089361;6132.04958718918;6133.916185115316;6141.670122563672;6298.244210694563;6307.274663413767;6319.399657032574;6323.114763933145;6326.557141000086;5780.94219057804;5892;6000.21997140369;6038.969805150946;6133.4786543142545;6052.334243233153;6064.9393506066135;6070.7875224366935;6083.178770865877;6122.448979591858;6021.548383871314;6029.939700602938;6030.819075427744;6031.638101713911;6036.215292012011;5778.797742079175;5782.710864456844;5835.56233282514;6000.5799594028;6027.819165425044;5901.7639294427745;5973.850653733692;5974.701264936649;5999.160117583455;6022.608530445507 01:59:20 INFO - PROCESS | 26687 | |1;ext.html;743.5538676793924;809.9149589293124;831.8544254755418;882.5146169606714;903.7921278106031;30433.847830760842;44599;45315;45337.773311133395;45451;44973.32540011902;45114;45127.77436112823;45177.54822451775;45271.32093018599;7662.961685191566;7812.726554570591;9168.083191680846;9314.72055838326;9316.534173291342;10586.629467968623;10812.891871081267;11147.275427097253;11170.441477926111;11170.832437513443;10943.23397362185;10948.124150067975;10949.671509854677;10954;10981.286216395909;10818.459077046195;10819.296745711503;10852.728681782939;10877.619283325084;10908;10676.039156475877;10726.141908647367;10765.892341076646;10770.515326810313;10775.892241077567;43557.56442435567;43775.34336984948;43991.78004109959;43996.120077598585;44071.33892991608;4107.178564287155;4119.773412462313;4161.625453709151;4229.344451610005;4269.594388533084;29032.709672903424;29258.561121583192;29553.70446295531;29565.70434295651;30566.541501877266;4369.606735393798;4452.354408610766;4480.820767169309;4483.193025255453;4570.7257564546235;233886;234036;234282;234766;235614;53622.46377536253;53873.461265386846;54218.45781542213;54230.728846355516;54269.1859622106;56723.43276567264;56743;56801;56802;56819;15335.386584536716;15579;15615.3753849847;15621.297041633154;15632.4528641497;12101.757964840654;12351.876481235253;13418.463261469627;13435.529756458673;13438.059335846592;13330.73338533224;13749.931250343685;13996.86003139976;14044;14126.081804682663;5208.140656791587;5232.189010703347;5240.737963101829;5244.659097158673;5393.784248630011;1007.7178390050798;1011.3931641015332;1013.6249587652616;1020.5815615597569;1022.0750221049962;28289.29276768056;28581.42837143287;28670.426591468055;28778.71221287802;28839.134825955254;1040.9011143941314;1074.2158224496006;1097.6542389147394;1105.5964572930823;1120.2830188679252;15485.845141548665;16899.310068993003;17173.656526869392;17239.655206895794;17362;321361;322170;323192;323533;323782;60369.698151508965;60390;60530.39469605336;60593.69703151456;60645.69677151586;1474.329180222996;1498.1610298233024;1527.1219049046842;1529.793477880494;1553.6115971007248;29927;31269.8436507816;31391.84304078465;31454.685453145175;32084.839575801972;43711.56288437075;43717.56282437135;43765.34351984659;43789;43859.78070109629;274610;276529;277081;277125;277260;30377.848110759303;31019.224519387197;31030;36064.09839753922;36729.63270367262;37497;44656.77671611621;44992.32511512395;48797;50117.24824127568;35215.82392088023;35242.29515409626;35295.11762205965;35371.11572210612;35381.469277960336;35233.29533409369;35357.823210883784;35364.64635353613;35418.11454713653;35426.645733543366;40153;40672.79663601663;40798.79600601978;40811.18377632371;41070;33299.500507491925;33413.49879751854;33441.665583343856;33441.83279083589;34076.65923340834;26973.460530789667;27001.244919080196;27009.459810804066;27297;27312.453750925266 01:59:20 INFO - PROCESS | 26687 | |2;jquery.html;455.3988734869969;464.55634868700383;467.4694222057963;472.10064826505504;477.1435273683737;20073.698894516594;21394;21818.454538636503;22353.888230558845;22461.663075053846;20791.16835326589;21194.89402552985;21983.23058692946;22516.66225006622;22523.09907603698;6646.069550262967;6757.864842703155;6869.381755641991;6874.346937040973;6969.1637003559645;6700.564463309894;6821.317868213188;6824.385805277524;7010.404115650176;7301.452391070665;7408.296211859892;7449.441291903102;7467;7467.738629147981;7468.701251949916;7255.1656559495805;7347.7795666130105;7383.889241661379;7392.667329970137;7409.703611855573;7140.643277777206;7195.676194571204;7212.386947109502;7389.261073892621;7397.149327827315;25075.37311567207;25300.367490812878;25477.49045019108;25872.094476693415;26208.86895565529;2837.233946834364;2892.7403629074283;2931.384409274055;3019.1999120233154;3028.1067085209697;15994.44019459325;16028.439004634898;16157.83842161575;16426.75359869603;16817.243224054946;2158.6761985702115;2242.932712018642;2305.6772051912744;2306.054517647773;2356.4815740537056;120290;123813.38093309566;127145;127207;127355;17826.821731782777;17913.820861791217;18380.08099595015;18592.628147436975;18760.906195468935;32855.507167392505;32869.3426131476;32904.3419131616;33155.171120721694;33186.66813331836;9897.109260166475;10026.546150808092;10149.797004059878;10301.124404425707;10318.535665895053;8488.575571221414;8571.400001999926;8580.785480362916;8760.693375731818;8780.385373023955;9085.727998080274;9204.447733136034;9349.579268932914;9388.718338449857;9425.245980321557;3657.3233951719017;3739.495168152319;3750.8345706570135;3895.7272990890365;3924.3328634132267;965.1844191658178;977.047378805673;985.8718366612294;987.5259875259771;989.1740396768635;21063;21246.575068498543;21273.68089478659;21369.25207617757;21689.34931952044;994.9054839790208;996.6212839121232;999.6351331763854;1004.4726518577659;1014.4674046125696;10872.836907446395;11780;11870.34713090771;11957.58148464817;11960.880391196151;622218;647849;654118;654958;655213;115297;122193;122562;122626.38686806509;123843.3807830955;1231.5443285984243;1264.905132115095;1278.1372573512842;1278.2969366848197;1290.2452065541574;17319.2206350712;18107.72838407451;18525.258989640264;18534.44396668102;18692.781777928107;49304.753476233825;49686.50313496819;49825.25262121144;50324.49675503198;51003.2349514765;62934.685326573075;64955;65673.34326656672;65974.34025659873;67521;8269;11095;12159.513619455476;12534.373281335715;12584.685382865502;14154.433822646979;14220.857791422368;14309;14430.134191948096;14615.853841461874;11996.520139194683;12253;12289;12406.62780116598;12463.1275810696;11467.369294688871;11689.181757276909;11743.941280293544;12003.939980300043;12004.939975300067;12710.618681439571;12783.680407989874;12798.872011279767;12825;12874.356282186041;12406.751864962831;12531.310777907303;12753.873145202117;12843.100982931479;12954.740905182032;12218.450169742206;12457.813132803196;12482.937585312015;12639.1784534005;12691.463896093705 01:59:20 INFO - PROCESS | 26687 | |3;mootools.html;1463.7950686903841;1564.1866229560628;1602.88779785415;1608.943686970956;1623.4399132299357;9567.808643827118;11275.887241127586;11658.941705291472;11967.46146423411;12095.334756588383;11598.710032249199;11707.765844683101;11713.355765432898;11721.648350549498;11733.7653246935;2032.7763945966005;2097.2030628361204;2443.804495640349;2481.5285095831823;2482.8013758899247;2448.51029794041;2513.5852584323566;2526.393665520271;2529.6837895263093;2531.240627811658;2416.8549887006748;2433.8174636902213;2434.050720219116;2443.5235129149823;2444.69441319835;2398.70016247969;2399.7720216579396;2404.158544509425;2406.470576473173;2407.3861165402886;2270.943226419336;2296.7588403217756;2300.332903457995;2301.2060839010514;2301.8964146613275;11633.127515436292;11682.591109311134;11689.883101168965;11697.88302116985;11701.239419437708;5891.322497912752;5944.494717948979;5965.612235204741;5993.820185394445;6005.249343832021;3371.1403592084107;3452.9482057769155;3718.0333113390443;3749.643783840531;3759.3045286622064;1375.0855680972143;1376.311844077961;1389.9166050037031;1396.8976690081363;1448.9347979340955;17774.28902843885;17789.46631601054;17810.99999999987;17848.375306864327;17855.196516156804;5037.370328708911;5111.693298402108;5170.689758614495;5171.379434467888;5253.185756207794;14255.073420227653;14336.85663143376;14369.13785172893;14382;14384.424623015171;5962.105684147413;6094.542909281821;6178.073289006685;6205.47253483459;6211.0372892201785;4608;4757.14371413149;5490.670559766427;5631.549476041907;5647.49172574462;4347.130573885235;4454.131444368317;4503.437070366204;4536.682432229778;4555.498895121534;1204.3677069538562;1205.3912774049224;1227.6448159772171;1231.0766924806396;1232.6117273058958;851.9403641745018;873.1225118755737;893.5175005496959;903.4172958441768;924.1497822003638;7016.7193312267955;7059.89410158848;7081.610511421818;7242.022326985895;7244.130704315516;1118.5078565431184;1119.361963680694;1127.4757237884423;1137.2721458266717;1165.341582006164;4364.5635436456105;4608.769561521911;4675.111728771522;4801.319538161685;4865.270209468608;14081.366338514792;14685.118892866249;14833.110013399231;14861.331240094221;14896.776548351783;14632.268386580628;14672.486462973744;14673.486427975184;14805.407783688532;14830.184339861411;10619.150467962545;10848.349099054081;10868.565257389773;10995.34027958309;11004.174686898676;6919;6930.5148639594745;6979.5114341997605;7019.017337572846;7057.117860267466;12872.22766634005;12999.350032498527;13080.345982701017;13515.797263041259;13521.729565408834;12816.871831281567;13045.478180872658;13316.800247996482;13443.731125377633;13523;9866.901330986886;10009.399436033862;10105.44420056904;10123;10224.64213752515;10984.176186785864;11008.944955275172;11015.669529914116;11041.503132359223;11165.944170279421;9290.1174388433;9308;9400.764980875532;9549.665761698307;9774.31579789408;9144.542772861463;9165;9226.861597075915;9271.907280927104;9575.473348965874;8183.95908020456;8349.457285276438;8351.749447516584;8363.581820909052;8370.53962032094;6004.159417681615;6009.45914867664;6066.969665151646;6094.878102438015;6171.876562468815;6221.191245138103;6242.812715618538;6374.26695929974;6392.64840433762;6430.339647452074 01:59:20 INFO - PROCESS | 26687 | |4;prototype.html;417.373939091363;417.5115115315082;419.96220340169396;421.93249080147183;424.2575492887445;5098.668586541872;5168.3539557555305;5180.63735538512;5191.922121168185;5213.113770658988;4930.408350997876;5027.44149313713;5106.872328191797;5123.333966584339;5165.974170129144;4117.402976568395;4120.217158739845;4122.3716631930365;4131.091159944807;4141.64795992341;4130.194612050651;4155.563665815092;4168;4170.416141740159;4176.269152898246;4090.754554726711;4135.338345864662;4160.750354978696;4229.154169166164;4270.871873843789;4266.296061149907;4274.422952901381;4275.871723848259;4279.7218180818145;4286.335617979218;4025.772139497452;4038.8788336349958;4048.9797551012352;4055;4063.613956674141;4841.298011788306;5010.423801262865;5058;5069.923951140736;5120.462351453119;4959.851204463872;5008.398992120932;5011.824586139468;5032.4967503249745;5037.924431133536;5452.59105567084;5458.563314934796;5461.9453805462235;5520.199571062214;5531.783007738291;4288.592583704543;4462.367203350039;4524.6380289576755;4556.088782243564;4575.87890966712;5366.221897824794;5431.592630552685;5592.6085174037835;5596.496315331639;5629.7185140742795;4541.5231400703115;4614.515475875052;4625.976870115628;4659.9767001165455;4661.86014419568;4729.503402142795;4753.263244197155;4753.904921901542;4787.640926930494;4798.1123492154065;4786.688865223749;4837.637177211724;4868.367112275381;4977.576905962961;4987.301777751118;5390.407055223922;5394.190871369247;5395.622306438511;5397.8380648580605;5458.372287186945;1588.5869673884642;1595.1067402254776;1627.3083939325738;1635.1742370103022;1642.786437763083;1231.9589946495198;1251.3805666195292;1266.9683257918625;1282.1152905229299;1282.435728279554;5340.412554618989;5343.171808369709;5381.412483317431;5399.4600539945695;5455.290812194468;5520.834374968757;5527.944720552823;5531.557475402039;5557.861053473695;5603.489191201123;5104.69371837691;5295.947040529622;5349.679019258857;5374.596905232123;5389.380221274525;2868.498012847743;2915.5043642580813;2918.212082737649;2927.370615317717;2940.720631540043;5760.683162426107;5803.187553742479;5855.063189889597;5904.76380944766;5915.7633694652595;5551;5653.632513886667;5668.801591944262;5733.967885780517;5738.995675756726;5782.450667186695;5825.94174058254;5846.239988801429;5893.499052580579;5897.52819774417;5298.470152984671;5491.643043202179;5558.91661625084;5571.467846342288;5609.186667933209;5241.921371179359;5335.33308336458;5467.808626698046;5473.917891231715;5485.985092757853;5528.530074943675;5635.605507614428;5767.154510556749;5767.423257674199;5802.0426629605645;4638;4644.373009643655;4654.348391225163;4655.72065676047;4660.533946605312;5490.450954904637;5533.612647114664;5551.361593416808;5588.2455868458865;5616.634918730269;5302.534321540502;5327.760250788801;5334.279872217357;5400.378956420062;5427.294451721251;5313.946860531345;5393.595480338912;5453.209284653783;5453.336732296529;5476.397596264487;5123.436421993503;5370.959035566616;5424.647397919122;5428.67427954324;5431.83704488866;5423.159410291411;5426.321709786277;5436.961139572661;5477.891011960503;5508.421615730291;5225.817096401608;5365.678059316453;5377.919331209957;5381.542568881689;5388.191771234347 01:59:20 INFO - PROCESS | 26687 | |5;yui.html;836.7824365664925;880.5773228850152;911.3529394130171;925.754675011122;941.1012483078661;16336.183190840455;17637.91181044096;17667.293308267654;17674.02792846393;17686.38097666582;17091.82908170918;17805.64388712225;17831.108444577785;17845.91077044616;17851.821481785177;867.2758246863845;873.3842640938155;885.8671199320121;901.143913282381;904.6652738486769;884.5975081338008;885.8272636835819;885.9424137431079;886.2555453419127;889.0531583863165;927.7866090799121;929.5723966975165;929.6885543342988;930.1675000874247;931.3247895275938;922.580225997173;923.3444254579257;924.6024209589874;925.3060204846365;925.5834874306555;680.2823021712092;680.384252251713;680.5100327764002;680.6154522694661;681.0533358631507;17018.23417946183;17178.398756043603;17275.481735547954;18573.257069717194;18726.812731872644;15081.245937703181;15712.842871571253;15880.682386352324;16125.596860078474;16430.260638271306;13590.184588924696;13682.110662806886;13890.4443822247;14028.158310501403;14330.283485825772;4111.71218014739;4123.2165888481095;4196.769177695225;4203.453551038376;4231.640310573605;89363.55318223432;89703.55148224282;89937;92478;93642.53178734063;12690;12702.237865728086;12712.110152289435;12766.106372553832;13043.804342934864;22873.88563057174;22889.542209155723;23395.06419743191;23698.407539811644;23882.52234955291;9076.183143517115;9211.631534738535;9239.538023098818;9241.861372079424;9258.444493330422;4957.705478598492;4992.500749925051;5011.774470148852;5075.670081444694;5123.3339665843205;3695.926081478355;3726.552813662324;3769.26499332633;3783.319002579562;3817.6564109230303;620.6679426506797;624.7407325959766;626.7430353555112;639.9328070552618;640.3020707428925;771.6064806948384;788.3299195683624;793.8015496125969;809.8299357134949;816.4774544291658;11308.943455282671;12126.27242365461;12171.269723816422;12383.050898039839;12433.75132497345;797.4537441852234;798.8282519258387;800.2517646001007;819.5902048975512;819.9385046121565;5659.547236221092;5682.090865461589;5751.1085781703905;5751.281089863767;5762.567807414461;81338;82675.58662206771;83780;85408;87605.56197218974;60491;75531.62234188794;75533;77190;77355;65406;67016;67475;67998.3200168002;69191.65404172947;10811.405372704578;10881.18391120685;11163;11166.218364714392;11210.215284929976;44492.55507445014;44644.77677611591;44918.77540612276;45429;45732.31401529046;51598.74200628973;51768.22347664713;51957;52149.7392513035;54152;12499.127921463027;12531.310777907303;12609.432575534307;12833.230006199658;12902.225866448043;14676.119432833637;14867.330970106155;15028.17345046033;15037.022593531823;15093;14225.573232803032;14351.210683412513;14355.784663230268;14368.209748463934;14431.639209019859;13222.404991775586;13482.730345393233;13485.595432137052;13687.589372318846;13858.792118118035;14237.359318830884;14312.928435357757;14314.570562883131;14326;14331.85668143347;10720.946395267974;10725.731856703645;10819.837702434626;11079.50142243585;11191.608293709682;11536.36549989725;11590.188686791844;11859;11933.641990740292;12060.457279422271 01:59:20 INFO - PROCESS | 26687 | __end_tp_report 01:59:20 INFO - PROCESS | 26687 | __start_cc_report 01:59:20 INFO - PROCESS | 26687 | _x_x_mozilla_cycle_collect,1476 01:59:20 INFO - PROCESS | 26687 | __end_cc_report 01:59:20 INFO - PROCESS | 26687 | __startTimestamp1491901160390__endTimestamp 01:59:20 INFO - PROCESS | 26687 | 01:59:20 INFO - PROCESS | 26687 | ------- Summary: start ------- 01:59:20 INFO - PROCESS | 26687 | Number of tests: 6 01:59:20 INFO - PROCESS | 26687 | 01:59:20 INFO - PROCESS | 26687 | [#0] dojo.html Cycles:165 Average:5739.80 Median:5999.16 stddev:668.56 (11.1%) stddev-sans-first:643.66 01:59:20 INFO - PROCESS | 26687 | Values: 3344.3 3433.8 3444.7 3481.5 3495.1 5749.1 5798.9 5822.1 5824.7 5832.3 5768.7 5824.0 5827.0 5830.5 5834.4 5157.5 5161.8 5211.4 5211.9 5251.5 4999.7 5027.0 5107.8 5252.3 5262.4 5204.0 5209.2 5227.3 5227.8 5249.3 5239.7 5239.7 5246.0 5256.4 5263.9 4903.9 4971.1 5109.5 5118.7 5181.1 5694.0 5742.9 5764.0 5772.3 5791.7 6171.0 6214.7 6215.3 6219.3 6221.8 6275.6 6303.3 6309.0 6312.3 6312.6 5568.6 5571.7 5796.9 5875.1 5883.3 6328.9 6338.1 6339.4 6339.8 6349.0 6118.0 6122.7 6135.2 6137.1 6141.7 5924.1 5961.3 6056.6 6119.3 6181.3 5892.4 6125.6 6134.6 6159.0 6160.8 6022.5 6033.5 6065.7 6110.8 6114.9 4663.0 4714.5 4721.1 4725.6 4743.7 4072.1 4118.1 4238.6 4251.0 4264.7 5891.4 5948.9 6018.2 6048.0 6094.6 6188.1 6209.1 6215.5 6220.0 6225.2 5704.3 5979.2 5987.7 5993.9 6030.2 5116.0 5187.8 5230.7 5265.8 5268.1 6541.4 6575.8 6576.3 6576.7 6594.5 6188.1 6190.6 6241.6 6255.4 6264.3 6139.6 6216.0 6403.6 6451.7 6565.2 6123.1 6128.3 6132.0 6133.9 6141.7 6298.2 6307.3 6319.4 6323.1 6326.6 5780.9 5892.0 6000.2 6039.0 6133.5 6052.3 6064.9 6070.8 6083.2 6122.4 6021.5 6029.9 6030.8 6031.6 6036.2 5778.8 5782.7 5835.6 6000.6 6027.8 5901.8 5973.9 5974.7 5999.2 6022.6 01:59:20 INFO - PROCESS | 26687 | 01:59:20 INFO - PROCESS | 26687 | [#1] ext.html Cycles:180 Average:45823.44 Median:322681.00 stddev:73029.27 (22.6%) stddev-sans-first:73155.70 01:59:20 INFO - PROCESS | 26687 | Values: 743.6 809.9 831.9 882.5 903.8 30433.8 44599.0 45315.0 45337.8 45451.0 44973.3 45114.0 45127.8 45177.5 45271.3 7663.0 7812.7 9168.1 9314.7 9316.5 10586.6 10812.9 11147.3 11170.4 11170.8 10943.2 10948.1 10949.7 10954.0 10981.3 10818.5 10819.3 10852.7 10877.6 10908.0 10676.0 10726.1 10765.9 10770.5 10775.9 43557.6 43775.3 43991.8 43996.1 44071.3 4107.2 4119.8 4161.6 4229.3 4269.6 29032.7 29258.6 29553.7 29565.7 30566.5 4369.6 4452.4 4480.8 4483.2 4570.7 233886.0 234036.0 234282.0 234766.0 235614.0 53622.5 53873.5 54218.5 54230.7 54269.2 56723.4 56743.0 56801.0 56802.0 56819.0 15335.4 15579.0 15615.4 15621.3 15632.5 12101.8 12351.9 13418.5 13435.5 13438.1 13330.7 13749.9 13996.9 14044.0 14126.1 5208.1 5232.2 5240.7 5244.7 5393.8 1007.7 1011.4 1013.6 1020.6 1022.1 28289.3 28581.4 28670.4 28778.7 28839.1 1040.9 1074.2 1097.7 1105.6 1120.3 15485.8 16899.3 17173.7 17239.7 17362.0 321361.0 322170.0 323192.0 323533.0 323782.0 60369.7 60390.0 60530.4 60593.7 60645.7 1474.3 1498.2 1527.1 1529.8 1553.6 29927.0 31269.8 31391.8 31454.7 32084.8 43711.6 43717.6 43765.3 43789.0 43859.8 274610.0 276529.0 277081.0 277125.0 277260.0 30377.8 31019.2 31030.0 36064.1 36729.6 37497.0 44656.8 44992.3 48797.0 50117.2 35215.8 35242.3 35295.1 35371.1 35381.5 35233.3 35357.8 35364.6 35418.1 35426.6 40153.0 40672.8 40798.8 40811.2 41070.0 33299.5 33413.5 33441.7 33441.8 34076.7 26973.5 27001.2 27009.5 27297.0 27312.5 01:59:20 INFO - PROCESS | 26687 | 01:59:20 INFO - PROCESS | 26687 | [#2] jquery.html Cycles:180 Average:37804.79 Median:22519.88 stddev:107043.13 (475.3%) stddev-sans-first:107306.68 01:59:20 INFO - PROCESS | 26687 | Values: 455.4 464.6 467.5 472.1 477.1 20073.7 21394.0 21818.5 22353.9 22461.7 20791.2 21194.9 21983.2 22516.7 22523.1 6646.1 6757.9 6869.4 6874.3 6969.2 6700.6 6821.3 6824.4 7010.4 7301.5 7408.3 7449.4 7467.0 7467.7 7468.7 7255.2 7347.8 7383.9 7392.7 7409.7 7140.6 7195.7 7212.4 7389.3 7397.1 25075.4 25300.4 25477.5 25872.1 26208.9 2837.2 2892.7 2931.4 3019.2 3028.1 15994.4 16028.4 16157.8 16426.8 16817.2 2158.7 2242.9 2305.7 2306.1 2356.5 120290.0 123813.4 127145.0 127207.0 127355.0 17826.8 17913.8 18380.1 18592.6 18760.9 32855.5 32869.3 32904.3 33155.2 33186.7 9897.1 10026.5 10149.8 10301.1 10318.5 8488.6 8571.4 8580.8 8760.7 8780.4 9085.7 9204.4 9349.6 9388.7 9425.2 3657.3 3739.5 3750.8 3895.7 3924.3 965.2 977.0 985.9 987.5 989.2 21063.0 21246.6 21273.7 21369.3 21689.3 994.9 996.6 999.6 1004.5 1014.5 10872.8 11780.0 11870.3 11957.6 11960.9 622218.0 647849.0 654118.0 654958.0 655213.0 115297.0 122193.0 122562.0 122626.4 123843.4 1231.5 1264.9 1278.1 1278.3 1290.2 17319.2 18107.7 18525.3 18534.4 18692.8 49304.8 49686.5 49825.3 50324.5 51003.2 62934.7 64955.0 65673.3 65974.3 67521.0 8269.0 11095.0 12159.5 12534.4 12584.7 14154.4 14220.9 14309.0 14430.1 14615.9 11996.5 12253.0 12289.0 12406.6 12463.1 11467.4 11689.2 11743.9 12003.9 12004.9 12710.6 12783.7 12798.9 12825.0 12874.4 12406.8 12531.3 12753.9 12843.1 12954.7 12218.5 12457.8 12482.9 12639.2 12691.5 01:59:20 INFO - PROCESS | 26687 | 01:59:20 INFO - PROCESS | 26687 | [#3] mootools.html Cycles:180 Average:7266.84 Median:2425.34 stddev:4689.33 (193.3%) stddev-sans-first:4682.21 01:59:20 INFO - PROCESS | 26687 | Values: 1463.8 1564.2 1602.9 1608.9 1623.4 9567.8 11275.9 11658.9 11967.5 12095.3 11598.7 11707.8 11713.4 11721.6 11733.8 2032.8 2097.2 2443.8 2481.5 2482.8 2448.5 2513.6 2526.4 2529.7 2531.2 2416.9 2433.8 2434.1 2443.5 2444.7 2398.7 2399.8 2404.2 2406.5 2407.4 2270.9 2296.8 2300.3 2301.2 2301.9 11633.1 11682.6 11689.9 11697.9 11701.2 5891.3 5944.5 5965.6 5993.8 6005.2 3371.1 3452.9 3718.0 3749.6 3759.3 1375.1 1376.3 1389.9 1396.9 1448.9 17774.3 17789.5 17811.0 17848.4 17855.2 5037.4 5111.7 5170.7 5171.4 5253.2 14255.1 14336.9 14369.1 14382.0 14384.4 5962.1 6094.5 6178.1 6205.5 6211.0 4608.0 4757.1 5490.7 5631.5 5647.5 4347.1 4454.1 4503.4 4536.7 4555.5 1204.4 1205.4 1227.6 1231.1 1232.6 851.9 873.1 893.5 903.4 924.1 7016.7 7059.9 7081.6 7242.0 7244.1 1118.5 1119.4 1127.5 1137.3 1165.3 4364.6 4608.8 4675.1 4801.3 4865.3 14081.4 14685.1 14833.1 14861.3 14896.8 14632.3 14672.5 14673.5 14805.4 14830.2 10619.2 10848.3 10868.6 10995.3 11004.2 6919.0 6930.5 6979.5 7019.0 7057.1 12872.2 12999.4 13080.3 13515.8 13521.7 12816.9 13045.5 13316.8 13443.7 13523.0 9866.9 10009.4 10105.4 10123.0 10224.6 10984.2 11008.9 11015.7 11041.5 11165.9 9290.1 9308.0 9400.8 9549.7 9774.3 9144.5 9165.0 9226.9 9271.9 9575.5 8184.0 8349.5 8351.7 8363.6 8370.5 6004.2 6009.5 6067.0 6094.9 6171.9 6221.2 6242.8 6374.3 6392.6 6430.3 01:59:20 INFO - PROCESS | 26687 | 01:59:20 INFO - PROCESS | 26687 | [#4] prototype.html Cycles:180 Average:4716.60 Median:5202.52 stddev:1264.79 (24.3%) stddev-sans-first:1226.48 01:59:20 INFO - PROCESS | 26687 | Values: 417.4 417.5 420.0 421.9 424.3 5098.7 5168.4 5180.6 5191.9 5213.1 4930.4 5027.4 5106.9 5123.3 5166.0 4117.4 4120.2 4122.4 4131.1 4141.6 4130.2 4155.6 4168.0 4170.4 4176.3 4090.8 4135.3 4160.8 4229.2 4270.9 4266.3 4274.4 4275.9 4279.7 4286.3 4025.8 4038.9 4049.0 4055.0 4063.6 4841.3 5010.4 5058.0 5069.9 5120.5 4959.9 5008.4 5011.8 5032.5 5037.9 5452.6 5458.6 5461.9 5520.2 5531.8 4288.6 4462.4 4524.6 4556.1 4575.9 5366.2 5431.6 5592.6 5596.5 5629.7 4541.5 4614.5 4626.0 4660.0 4661.9 4729.5 4753.3 4753.9 4787.6 4798.1 4786.7 4837.6 4868.4 4977.6 4987.3 5390.4 5394.2 5395.6 5397.8 5458.4 1588.6 1595.1 1627.3 1635.2 1642.8 1232.0 1251.4 1267.0 1282.1 1282.4 5340.4 5343.2 5381.4 5399.5 5455.3 5520.8 5527.9 5531.6 5557.9 5603.5 5104.7 5295.9 5349.7 5374.6 5389.4 2868.5 2915.5 2918.2 2927.4 2940.7 5760.7 5803.2 5855.1 5904.8 5915.8 5551.0 5653.6 5668.8 5734.0 5739.0 5782.5 5825.9 5846.2 5893.5 5897.5 5298.5 5491.6 5558.9 5571.5 5609.2 5241.9 5335.3 5467.8 5473.9 5486.0 5528.5 5635.6 5767.2 5767.4 5802.0 4638.0 4644.4 4654.3 4655.7 4660.5 5490.5 5533.6 5551.4 5588.2 5616.6 5302.5 5327.8 5334.3 5400.4 5427.3 5313.9 5393.6 5453.2 5453.3 5476.4 5123.4 5371.0 5424.6 5428.7 5431.8 5423.2 5426.3 5437.0 5477.9 5508.4 5225.8 5365.7 5377.9 5381.5 5388.2 01:59:20 INFO - PROCESS | 26687 | 01:59:20 INFO - PROCESS | 26687 | [#5] yui.html Cycles:180 Average:18969.25 Median:44568.67 stddev:24167.33 (54.2%) stddev-sans-first:24196.77 01:59:20 INFO - PROCESS | 26687 | Values: 836.8 880.6 911.4 925.8 941.1 16336.2 17637.9 17667.3 17674.0 17686.4 17091.8 17805.6 17831.1 17845.9 17851.8 867.3 873.4 885.9 901.1 904.7 884.6 885.8 885.9 886.3 889.1 927.8 929.6 929.7 930.2 931.3 922.6 923.3 924.6 925.3 925.6 680.3 680.4 680.5 680.6 681.1 17018.2 17178.4 17275.5 18573.3 18726.8 15081.2 15712.8 15880.7 16125.6 16430.3 13590.2 13682.1 13890.4 14028.2 14330.3 4111.7 4123.2 4196.8 4203.5 4231.6 89363.6 89703.6 89937.0 92478.0 93642.5 12690.0 12702.2 12712.1 12766.1 13043.8 22873.9 22889.5 23395.1 23698.4 23882.5 9076.2 9211.6 9239.5 9241.9 9258.4 4957.7 4992.5 5011.8 5075.7 5123.3 3695.9 3726.6 3769.3 3783.3 3817.7 620.7 624.7 626.7 639.9 640.3 771.6 788.3 793.8 809.8 816.5 11308.9 12126.3 12171.3 12383.1 12433.8 797.5 798.8 800.3 819.6 819.9 5659.5 5682.1 5751.1 5751.3 5762.6 81338.0 82675.6 83780.0 85408.0 87605.6 60491.0 75531.6 75533.0 77190.0 77355.0 65406.0 67016.0 67475.0 67998.3 69191.7 10811.4 10881.2 11163.0 11166.2 11210.2 44492.6 44644.8 44918.8 45429.0 45732.3 51598.7 51768.2 51957.0 52149.7 54152.0 12499.1 12531.3 12609.4 12833.2 12902.2 14676.1 14867.3 15028.2 15037.0 15093.0 14225.6 14351.2 14355.8 14368.2 14431.6 13222.4 13482.7 13485.6 13687.6 13858.8 14237.4 14312.9 14314.6 14326.0 14331.9 10720.9 10725.7 10819.8 11079.5 11191.6 11536.4 11590.2 11859.0 11933.6 12060.5 01:59:20 INFO - PROCESS | 26687 | -------- Summary: end -------- 01:59:20 INFO - PROCESS | 26687 | 01:59:21 INFO - TEST-INFO | 26687: exit 0 01:59:21 INFO - TEST-OK | dromaeo_css | took 1115601ms 01:59:21 INFO - TEST-START | kraken 01:59:21 INFO - operating with platform_type : linux_ 01:59:21 INFO - Initialising browser for kraken test... 01:59:21 INFO - TEST-INFO | started process 27013 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWLZwiv/profile http://localhost:55965/getInfo.html) 01:59:25 INFO - PROCESS | 27013 | __metrics Screen width/height:1600/1200 01:59:25 INFO - PROCESS | 27013 | colorDepth:24 01:59:25 INFO - PROCESS | 27013 | Browser inner width/height: 1024/697 01:59:25 INFO - PROCESS | 27013 | __metrics 01:59:25 INFO - PROCESS | 27013 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:59:25 INFO - PROCESS | 27013 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:59:26 INFO - TEST-INFO | 27013: exit 0 01:59:26 INFO - Browser initialized. 01:59:26 INFO - Running cycle 1/1 for kraken test... 01:59:26 INFO - TEST-INFO | started process 27110 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWLZwiv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:59:38 INFO - PROCESS | 27110 | RSS: Main: 140173312 01:59:38 INFO - PROCESS | 27110 | 01:59:41 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55965/tests/kraken/driver.html?testName=audio-beat-detection) 01:59:42 INFO - PROCESS | 27110 | RSS: Main: 183365632 01:59:42 INFO - PROCESS | 27110 | 01:59:44 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55965/tests/kraken/driver.html?testName=audio-dft) 01:59:45 INFO - PROCESS | 27110 | RSS: Main: 173637632 01:59:45 INFO - PROCESS | 27110 | 01:59:48 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55965/tests/kraken/driver.html?testName=audio-fft) 01:59:48 INFO - PROCESS | 27110 | RSS: Main: 265199616 01:59:48 INFO - PROCESS | 27110 | 01:59:50 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55965/tests/kraken/driver.html?testName=audio-oscillator) 01:59:51 INFO - PROCESS | 27110 | RSS: Main: 171683840 01:59:51 INFO - PROCESS | 27110 | 01:59:53 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55965/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:59:53 INFO - PROCESS | 27110 | RSS: Main: 174125056 01:59:53 INFO - PROCESS | 27110 | 01:59:56 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55965/tests/kraken/driver.html?testName=imaging-darkroom) 01:59:57 INFO - PROCESS | 27110 | RSS: Main: 212824064 01:59:57 INFO - PROCESS | 27110 | 02:00:00 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55965/tests/kraken/driver.html?testName=imaging-desaturate) 02:00:01 INFO - PROCESS | 27110 | RSS: Main: 211603456 02:00:01 INFO - PROCESS | 27110 | 02:00:04 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55965/tests/kraken/driver.html?testName=json-parse-financial) 02:00:04 INFO - PROCESS | 27110 | RSS: Main: 210255872 02:00:04 INFO - PROCESS | 27110 | 02:00:06 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55965/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:00:07 INFO - PROCESS | 27110 | RSS: Main: 172953600 02:00:07 INFO - PROCESS | 27110 | 02:00:08 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55965/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:00:09 INFO - PROCESS | 27110 | RSS: Main: 179003392 02:00:09 INFO - PROCESS | 27110 | 02:00:11 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55965/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:00:11 INFO - PROCESS | 27110 | RSS: Main: 189157376 02:00:11 INFO - PROCESS | 27110 | 02:00:14 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55965/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:00:15 INFO - PROCESS | 27110 | RSS: Main: 197849088 02:00:15 INFO - PROCESS | 27110 | 02:00:17 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55965/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:00:18 INFO - PROCESS | 27110 | RSS: Main: 194412544 02:00:18 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | Cycle 1(1): loaded http://localhost:55965/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55965/tests/kraken/driver.html?testName=ai-astar) 02:00:20 INFO - PROCESS | 27110 | RSS: Main: 244424704 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | __start_tp_report 02:00:20 INFO - PROCESS | 27110 | _x_x_mozilla_page_load 02:00:20 INFO - PROCESS | 27110 | _x_x_mozilla_page_load_details 02:00:20 INFO - PROCESS | 27110 | |i|pagename|runs| 02:00:20 INFO - PROCESS | 27110 | |0;ai-astar;103;114;113;115;114;112;113;113;112;113 02:00:20 INFO - PROCESS | 27110 | |1;audio-beat-detection;144;116;116;157;120;115;116;118;132;114 02:00:20 INFO - PROCESS | 27110 | |2;audio-dft;159;161;160;161;162;161;160;162;162;162 02:00:20 INFO - PROCESS | 27110 | |3;audio-fft;75;74;75;112;77;76;76;76;89;75 02:00:20 INFO - PROCESS | 27110 | |4;audio-oscillator;82;81;103;83;83;83;99;82;82;107 02:00:20 INFO - PROCESS | 27110 | |5;imaging-gaussian-blur;113;113;113;112;113;112;113;113;113;113 02:00:20 INFO - PROCESS | 27110 | |6;imaging-darkroom;148;146;146;147;145;146;145;147;145;146 02:00:20 INFO - PROCESS | 27110 | |7;imaging-desaturate;94;95;95;94;93;94;94;94;94;93 02:00:20 INFO - PROCESS | 27110 | |8;json-parse-financial;57;53;56;54;54;55;54;54;54;53 02:00:20 INFO - PROCESS | 27110 | |9;json-stringify-tinderbox;52;52;52;53;53;52;53;53;53;53 02:00:20 INFO - PROCESS | 27110 | |10;stanford-crypto-aes;85;75;81;75;76;82;80;80;80;85 02:00:20 INFO - PROCESS | 27110 | |11;stanford-crypto-ccm;131;122;129;123;118;128;120;124;155;123 02:00:20 INFO - PROCESS | 27110 | |12;stanford-crypto-pbkdf2;161;161;159;164;159;165;161;160;159;157 02:00:20 INFO - PROCESS | 27110 | |13;stanford-crypto-sha256-iterative;62;61;60;59;59;60;60;66;58;58 02:00:20 INFO - PROCESS | 27110 | __end_tp_report 02:00:20 INFO - PROCESS | 27110 | __start_cc_report 02:00:20 INFO - PROCESS | 27110 | _x_x_mozilla_cycle_collect,1664 02:00:20 INFO - PROCESS | 27110 | __end_cc_report 02:00:20 INFO - PROCESS | 27110 | __startTimestamp1491901220300__endTimestamp 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | ------- Summary: start ------- 02:00:20 INFO - PROCESS | 27110 | Number of tests: 14 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:3.36 (3.0%) stddev-sans-first:0.97 02:00:20 INFO - PROCESS | 27110 | Values: 103.0 114.0 113.0 115.0 114.0 112.0 113.0 113.0 112.0 113.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#1] audio-beat-detection Cycles:10 Average:124.80 Median:119.00 stddev:14.80 (12.4%) stddev-sans-first:13.97 02:00:20 INFO - PROCESS | 27110 | Values: 144.0 116.0 116.0 157.0 120.0 115.0 116.0 118.0 132.0 114.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.05 (0.7%) stddev-sans-first:0.83 02:00:20 INFO - PROCESS | 27110 | Values: 159.0 161.0 160.0 161.0 162.0 161.0 160.0 162.0 162.0 162.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#3] audio-fft Cycles:10 Average:80.50 Median:76.00 stddev:11.88 (15.6%) stddev-sans-first:12.43 02:00:20 INFO - PROCESS | 27110 | Values: 75.0 74.0 75.0 112.0 77.0 76.0 76.0 76.0 89.0 75.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:82.50 stddev:10.20 (12.4%) stddev-sans-first:10.54 02:00:20 INFO - PROCESS | 27110 | Values: 82.0 81.0 103.0 83.0 83.0 83.0 99.0 82.0 82.0 107.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 02:00:20 INFO - PROCESS | 27110 | Values: 113.0 113.0 113.0 112.0 113.0 112.0 113.0 113.0 113.0 113.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.99 (0.7%) stddev-sans-first:0.78 02:00:20 INFO - PROCESS | 27110 | Values: 148.0 146.0 146.0 147.0 145.0 146.0 145.0 147.0 145.0 146.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 02:00:20 INFO - PROCESS | 27110 | Values: 94.0 95.0 95.0 94.0 93.0 94.0 94.0 94.0 94.0 93.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:1.26 (2.3%) stddev-sans-first:0.93 02:00:20 INFO - PROCESS | 27110 | Values: 57.0 53.0 56.0 54.0 54.0 55.0 54.0 54.0 54.0 53.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#9] json-stringify-tinderbox Cycles:10 Average:52.60 Median:53.00 stddev:0.52 (1.0%) stddev-sans-first:0.50 02:00:20 INFO - PROCESS | 27110 | Values: 52.0 52.0 52.0 53.0 53.0 52.0 53.0 53.0 53.0 53.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:80.50 stddev:3.67 (4.6%) stddev-sans-first:3.39 02:00:20 INFO - PROCESS | 27110 | Values: 85.0 75.0 81.0 75.0 76.0 82.0 80.0 80.0 80.0 85.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:126.00 stddev:10.54 (8.4%) stddev-sans-first:11.10 02:00:20 INFO - PROCESS | 27110 | Values: 131.0 122.0 129.0 123.0 118.0 128.0 120.0 124.0 155.0 123.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.60 Median:161.00 stddev:2.41 (1.5%) stddev-sans-first:2.55 02:00:20 INFO - PROCESS | 27110 | Values: 161.0 161.0 159.0 164.0 159.0 165.0 161.0 160.0 159.0 157.0 02:00:20 INFO - PROCESS | 27110 | 02:00:20 INFO - PROCESS | 27110 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.30 Median:60.00 stddev:2.36 (3.9%) stddev-sans-first:2.42 02:00:20 INFO - PROCESS | 27110 | Values: 62.0 61.0 60.0 59.0 59.0 60.0 60.0 66.0 58.0 58.0 02:00:20 INFO - PROCESS | 27110 | -------- Summary: end -------- 02:00:20 INFO - PROCESS | 27110 | 02:00:21 INFO - TEST-INFO | 27110: exit 0 02:00:21 INFO - TEST-OK | kraken | took 60237ms 02:00:21 INFO - SUITE-END | took 1175s 02:00:21 INFO - Completed test suite (00:19:35) 02:00:21 INFO - javascript benchmark 02:00:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7882.723163626751, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3344.347852168827, 3433.793972361657, 3444.6727560881723, 3481.4603736420863, 3495.1262184453885, 5749.080147176453, 5798.9130163047585, 5822.1266809978415, 5824.708764561776, 5832.329282132556, 5768.682722450274, 5824.0390335594575, 5826.9708651456685, 5830.475257226849, 5834.358220595731, 5157.5358217760395, 5161.8451446456575, 5211.400688920785, 5211.895762084756, 5251.474852514756, 4999.725015124167, 5026.97486512567, 5107.821226257063, 5252.264682944372, 5262.4474430184855, 5203.973980130113, 5209.166533354664, 5227.32044834171, 5227.764750586232, 5249.317588713462, 5239.659422137549, 5239.711815850127, 5246.0, 5256.448072952361, 5263.86840328991, 4903.926441103386, 4971.105201063807, 5109.540141387293, 5118.718470484122, 5181.093308670967, 5694.0, 5742.885142297172, 5764.048931926227, 5772.30732312121, 5791.681457519835, 6170.969145154246, 6214.65819379934, 6215.316315205324, 6219.253689557237, 6221.813345599639, 6275.623462592259, 6303.3066362700065, 6308.968455157741, 6312.274088479838, 6312.621242725451, 5568.582356323252, 5571.693556854371, 5796.913046304308, 5875.148103524965, 5883.323417806964, 6328.936710632926, 6338.144350512714, 6339.397757213057, 6339.841503962346, 6349.047642853517, 6117.969410153009, 6122.724477398528, 6135.202423684982, 6137.110119032715, 6141.723622437001, 5924.052151655715, 5961.314448838352, 6056.636601803818, 6119.326874043852, 6181.289151747516, 5892.351841297454, 6125.6017461851925, 6134.601250918676, 6158.969205154035, 6160.784372546939, 6022.45797878193, 6033.5474839387225, 6065.727042283108, 6110.779634049289, 6114.945045103268, 4663.044075964419, 4714.504976977437, 4721.126591580569, 4725.645576581702, 4743.66794324394, 4072.0634254121123, 4118.135191609796, 4238.618524332764, 4251.043515209115, 4264.722793018444, 5891.410858914074, 5948.881022379528, 6018.187544681412, 6048.032314829607, 6094.603850749687, 6188.071789231652, 6209.130721698965, 6215.471684906834, 6219.968900155471, 6225.159603453566, 5704.3154821421695, 5979.1629171915965, 5987.700614969322, 5993.940060599425, 6030.155778190969, 5115.974420127875, 5187.818426355059, 5230.712310822865, 5265.789368425297, 5268.051750684914, 6541.411272985454, 6575.835604109936, 6576.342365763386, 6576.736930522821, 6594.505412094112, 6188.102725104744, 6190.597611155261, 6241.594296370721, 6255.37446255389, 6264.34224406449, 6139.584134579286, 6215.96892015537, 6403.64779937108, 6451.677416129269, 6565.244996825426, 6123.069106197319, 6128.258691089361, 6132.04958718918, 6133.916185115316, 6141.670122563672, 6298.244210694563, 6307.274663413767, 6319.399657032574, 6323.114763933145, 6326.557141000086, 5780.94219057804, 5892.0, 6000.21997140369, 6038.969805150946, 6133.4786543142545, 6052.334243233153, 6064.9393506066135, 6070.7875224366935, 6083.178770865877, 6122.448979591858, 6021.548383871314, 6029.939700602938, 6030.819075427744, 6031.638101713911, 6036.215292012011, 5778.797742079175, 5782.710864456844, 5835.56233282514, 6000.5799594028, 6027.819165425044, 5901.7639294427745, 5973.850653733692, 5974.701264936649, 5999.160117583455, 6022.608530445507], "value": 5694.698729834779, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.5538676793924, 809.9149589293124, 831.8544254755418, 882.5146169606714, 903.7921278106031, 30433.847830760842, 44599.0, 45315.0, 45337.773311133395, 45451.0, 44973.32540011902, 45114.0, 45127.77436112823, 45177.54822451775, 45271.32093018599, 7662.961685191566, 7812.726554570591, 9168.083191680846, 9314.72055838326, 9316.534173291342, 10586.629467968623, 10812.891871081267, 11147.275427097253, 11170.441477926111, 11170.832437513443, 10943.23397362185, 10948.124150067975, 10949.671509854677, 10954.0, 10981.286216395909, 10818.459077046195, 10819.296745711503, 10852.728681782939, 10877.619283325084, 10908.0, 10676.039156475877, 10726.141908647367, 10765.892341076646, 10770.515326810313, 10775.892241077567, 43557.56442435567, 43775.34336984948, 43991.78004109959, 43996.120077598585, 44071.33892991608, 4107.178564287155, 4119.773412462313, 4161.625453709151, 4229.344451610005, 4269.594388533084, 29032.709672903424, 29258.561121583192, 29553.70446295531, 29565.70434295651, 30566.541501877266, 4369.606735393798, 4452.354408610766, 4480.820767169309, 4483.193025255453, 4570.7257564546235, 233886.0, 234036.0, 234282.0, 234766.0, 235614.0, 53622.46377536253, 53873.461265386846, 54218.45781542213, 54230.728846355516, 54269.1859622106, 56723.43276567264, 56743.0, 56801.0, 56802.0, 56819.0, 15335.386584536716, 15579.0, 15615.3753849847, 15621.297041633154, 15632.4528641497, 12101.757964840654, 12351.876481235253, 13418.463261469627, 13435.529756458673, 13438.059335846592, 13330.73338533224, 13749.931250343685, 13996.86003139976, 14044.0, 14126.081804682663, 5208.140656791587, 5232.189010703347, 5240.737963101829, 5244.659097158673, 5393.784248630011, 1007.7178390050798, 1011.3931641015332, 1013.6249587652616, 1020.5815615597569, 1022.0750221049962, 28289.29276768056, 28581.42837143287, 28670.426591468055, 28778.71221287802, 28839.134825955254, 1040.9011143941314, 1074.2158224496006, 1097.6542389147394, 1105.5964572930823, 1120.2830188679252, 15485.845141548665, 16899.310068993003, 17173.656526869392, 17239.655206895794, 17362.0, 321361.0, 322170.0, 323192.0, 323533.0, 323782.0, 60369.698151508965, 60390.0, 60530.39469605336, 60593.69703151456, 60645.69677151586, 1474.329180222996, 1498.1610298233024, 1527.1219049046842, 1529.793477880494, 1553.6115971007248, 29927.0, 31269.8436507816, 31391.84304078465, 31454.685453145175, 32084.839575801972, 43711.56288437075, 43717.56282437135, 43765.34351984659, 43789.0, 43859.78070109629, 274610.0, 276529.0, 277081.0, 277125.0, 277260.0, 30377.848110759303, 31019.224519387197, 31030.0, 36064.09839753922, 36729.63270367262, 37497.0, 44656.77671611621, 44992.32511512395, 48797.0, 50117.24824127568, 35215.82392088023, 35242.29515409626, 35295.11762205965, 35371.11572210612, 35381.469277960336, 35233.29533409369, 35357.823210883784, 35364.64635353613, 35418.11454713653, 35426.645733543366, 40153.0, 40672.79663601663, 40798.79600601978, 40811.18377632371, 41070.0, 33299.500507491925, 33413.49879751854, 33441.665583343856, 33441.83279083589, 34076.65923340834, 26973.460530789667, 27001.244919080196, 27009.459810804066, 27297.0, 27312.453750925266], "value": 19337.09059147646, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.3988734869969, 464.55634868700383, 467.4694222057963, 472.10064826505504, 477.1435273683737, 20073.698894516594, 21394.0, 21818.454538636503, 22353.888230558845, 22461.663075053846, 20791.16835326589, 21194.89402552985, 21983.23058692946, 22516.66225006622, 22523.09907603698, 6646.069550262967, 6757.864842703155, 6869.381755641991, 6874.346937040973, 6969.1637003559645, 6700.564463309894, 6821.317868213188, 6824.385805277524, 7010.404115650176, 7301.452391070665, 7408.296211859892, 7449.441291903102, 7467.0, 7467.738629147981, 7468.701251949916, 7255.1656559495805, 7347.7795666130105, 7383.889241661379, 7392.667329970137, 7409.703611855573, 7140.643277777206, 7195.676194571204, 7212.386947109502, 7389.261073892621, 7397.149327827315, 25075.37311567207, 25300.367490812878, 25477.49045019108, 25872.094476693415, 26208.86895565529, 2837.233946834364, 2892.7403629074283, 2931.384409274055, 3019.1999120233154, 3028.1067085209697, 15994.44019459325, 16028.439004634898, 16157.83842161575, 16426.75359869603, 16817.243224054946, 2158.6761985702115, 2242.932712018642, 2305.6772051912744, 2306.054517647773, 2356.4815740537056, 120290.0, 123813.38093309566, 127145.0, 127207.0, 127355.0, 17826.821731782777, 17913.820861791217, 18380.08099595015, 18592.628147436975, 18760.906195468935, 32855.507167392505, 32869.3426131476, 32904.3419131616, 33155.171120721694, 33186.66813331836, 9897.109260166475, 10026.546150808092, 10149.797004059878, 10301.124404425707, 10318.535665895053, 8488.575571221414, 8571.400001999926, 8580.785480362916, 8760.693375731818, 8780.385373023955, 9085.727998080274, 9204.447733136034, 9349.579268932914, 9388.718338449857, 9425.245980321557, 3657.3233951719017, 3739.495168152319, 3750.8345706570135, 3895.7272990890365, 3924.3328634132267, 965.1844191658178, 977.047378805673, 985.8718366612294, 987.5259875259771, 989.1740396768635, 21063.0, 21246.575068498543, 21273.68089478659, 21369.25207617757, 21689.34931952044, 994.9054839790208, 996.6212839121232, 999.6351331763854, 1004.4726518577659, 1014.4674046125696, 10872.836907446395, 11780.0, 11870.34713090771, 11957.58148464817, 11960.880391196151, 622218.0, 647849.0, 654118.0, 654958.0, 655213.0, 115297.0, 122193.0, 122562.0, 122626.38686806509, 123843.3807830955, 1231.5443285984243, 1264.905132115095, 1278.1372573512842, 1278.2969366848197, 1290.2452065541574, 17319.2206350712, 18107.72838407451, 18525.258989640264, 18534.44396668102, 18692.781777928107, 49304.753476233825, 49686.50313496819, 49825.25262121144, 50324.49675503198, 51003.2349514765, 62934.685326573075, 64955.0, 65673.34326656672, 65974.34025659873, 67521.0, 8269.0, 11095.0, 12159.513619455476, 12534.373281335715, 12584.685382865502, 14154.433822646979, 14220.857791422368, 14309.0, 14430.134191948096, 14615.853841461874, 11996.520139194683, 12253.0, 12289.0, 12406.62780116598, 12463.1275810696, 11467.369294688871, 11689.181757276909, 11743.941280293544, 12003.939980300043, 12004.939975300067, 12710.618681439571, 12783.680407989874, 12798.872011279767, 12825.0, 12874.356282186041, 12406.751864962831, 12531.310777907303, 12753.873145202117, 12843.100982931479, 12954.740905182032, 12218.450169742206, 12457.813132803196, 12482.937585312015, 12639.1784534005, 12691.463896093705], "value": 11566.582045717907, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1463.7950686903841, 1564.1866229560628, 1602.88779785415, 1608.943686970956, 1623.4399132299357, 9567.808643827118, 11275.887241127586, 11658.941705291472, 11967.46146423411, 12095.334756588383, 11598.710032249199, 11707.765844683101, 11713.355765432898, 11721.648350549498, 11733.7653246935, 2032.7763945966005, 2097.2030628361204, 2443.804495640349, 2481.5285095831823, 2482.8013758899247, 2448.51029794041, 2513.5852584323566, 2526.393665520271, 2529.6837895263093, 2531.240627811658, 2416.8549887006748, 2433.8174636902213, 2434.050720219116, 2443.5235129149823, 2444.69441319835, 2398.70016247969, 2399.7720216579396, 2404.158544509425, 2406.470576473173, 2407.3861165402886, 2270.943226419336, 2296.7588403217756, 2300.332903457995, 2301.2060839010514, 2301.8964146613275, 11633.127515436292, 11682.591109311134, 11689.883101168965, 11697.88302116985, 11701.239419437708, 5891.322497912752, 5944.494717948979, 5965.612235204741, 5993.820185394445, 6005.249343832021, 3371.1403592084107, 3452.9482057769155, 3718.0333113390443, 3749.643783840531, 3759.3045286622064, 1375.0855680972143, 1376.311844077961, 1389.9166050037031, 1396.8976690081363, 1448.9347979340955, 17774.28902843885, 17789.46631601054, 17810.99999999987, 17848.375306864327, 17855.196516156804, 5037.370328708911, 5111.693298402108, 5170.689758614495, 5171.379434467888, 5253.185756207794, 14255.073420227653, 14336.85663143376, 14369.13785172893, 14382.0, 14384.424623015171, 5962.105684147413, 6094.542909281821, 6178.073289006685, 6205.47253483459, 6211.0372892201785, 4608.0, 4757.14371413149, 5490.670559766427, 5631.549476041907, 5647.49172574462, 4347.130573885235, 4454.131444368317, 4503.437070366204, 4536.682432229778, 4555.498895121534, 1204.3677069538562, 1205.3912774049224, 1227.6448159772171, 1231.0766924806396, 1232.6117273058958, 851.9403641745018, 873.1225118755737, 893.5175005496959, 903.4172958441768, 924.1497822003638, 7016.7193312267955, 7059.89410158848, 7081.610511421818, 7242.022326985895, 7244.130704315516, 1118.5078565431184, 1119.361963680694, 1127.4757237884423, 1137.2721458266717, 1165.341582006164, 4364.5635436456105, 4608.769561521911, 4675.111728771522, 4801.319538161685, 4865.270209468608, 14081.366338514792, 14685.118892866249, 14833.110013399231, 14861.331240094221, 14896.776548351783, 14632.268386580628, 14672.486462973744, 14673.486427975184, 14805.407783688532, 14830.184339861411, 10619.150467962545, 10848.349099054081, 10868.565257389773, 10995.34027958309, 11004.174686898676, 6919.0, 6930.5148639594745, 6979.5114341997605, 7019.017337572846, 7057.117860267466, 12872.22766634005, 12999.350032498527, 13080.345982701017, 13515.797263041259, 13521.729565408834, 12816.871831281567, 13045.478180872658, 13316.800247996482, 13443.731125377633, 13523.0, 9866.901330986886, 10009.399436033862, 10105.44420056904, 10123.0, 10224.64213752515, 10984.176186785864, 11008.944955275172, 11015.669529914116, 11041.503132359223, 11165.944170279421, 9290.1174388433, 9308.0, 9400.764980875532, 9549.665761698307, 9774.31579789408, 9144.542772861463, 9165.0, 9226.861597075915, 9271.907280927104, 9575.473348965874, 8183.95908020456, 8349.457285276438, 8351.749447516584, 8363.581820909052, 8370.53962032094, 6004.159417681615, 6009.45914867664, 6066.969665151646, 6094.878102438015, 6171.876562468815, 6221.191245138103, 6242.812715618538, 6374.26695929974, 6392.64840433762, 6430.339647452074], "value": 5479.473199962668, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.373939091363, 417.5115115315082, 419.96220340169396, 421.93249080147183, 424.2575492887445, 5098.668586541872, 5168.3539557555305, 5180.63735538512, 5191.922121168185, 5213.113770658988, 4930.408350997876, 5027.44149313713, 5106.872328191797, 5123.333966584339, 5165.974170129144, 4117.402976568395, 4120.217158739845, 4122.3716631930365, 4131.091159944807, 4141.64795992341, 4130.194612050651, 4155.563665815092, 4168.0, 4170.416141740159, 4176.269152898246, 4090.754554726711, 4135.338345864662, 4160.750354978696, 4229.154169166164, 4270.871873843789, 4266.296061149907, 4274.422952901381, 4275.871723848259, 4279.7218180818145, 4286.335617979218, 4025.772139497452, 4038.8788336349958, 4048.9797551012352, 4055.0, 4063.613956674141, 4841.298011788306, 5010.423801262865, 5058.0, 5069.923951140736, 5120.462351453119, 4959.851204463872, 5008.398992120932, 5011.824586139468, 5032.4967503249745, 5037.924431133536, 5452.59105567084, 5458.563314934796, 5461.9453805462235, 5520.199571062214, 5531.783007738291, 4288.592583704543, 4462.367203350039, 4524.6380289576755, 4556.088782243564, 4575.87890966712, 5366.221897824794, 5431.592630552685, 5592.6085174037835, 5596.496315331639, 5629.7185140742795, 4541.5231400703115, 4614.515475875052, 4625.976870115628, 4659.9767001165455, 4661.86014419568, 4729.503402142795, 4753.263244197155, 4753.904921901542, 4787.640926930494, 4798.1123492154065, 4786.688865223749, 4837.637177211724, 4868.367112275381, 4977.576905962961, 4987.301777751118, 5390.407055223922, 5394.190871369247, 5395.622306438511, 5397.8380648580605, 5458.372287186945, 1588.5869673884642, 1595.1067402254776, 1627.3083939325738, 1635.1742370103022, 1642.786437763083, 1231.9589946495198, 1251.3805666195292, 1266.9683257918625, 1282.1152905229299, 1282.435728279554, 5340.412554618989, 5343.171808369709, 5381.412483317431, 5399.4600539945695, 5455.290812194468, 5520.834374968757, 5527.944720552823, 5531.557475402039, 5557.861053473695, 5603.489191201123, 5104.69371837691, 5295.947040529622, 5349.679019258857, 5374.596905232123, 5389.380221274525, 2868.498012847743, 2915.5043642580813, 2918.212082737649, 2927.370615317717, 2940.720631540043, 5760.683162426107, 5803.187553742479, 5855.063189889597, 5904.76380944766, 5915.7633694652595, 5551.0, 5653.632513886667, 5668.801591944262, 5733.967885780517, 5738.995675756726, 5782.450667186695, 5825.94174058254, 5846.239988801429, 5893.499052580579, 5897.52819774417, 5298.470152984671, 5491.643043202179, 5558.91661625084, 5571.467846342288, 5609.186667933209, 5241.921371179359, 5335.33308336458, 5467.808626698046, 5473.917891231715, 5485.985092757853, 5528.530074943675, 5635.605507614428, 5767.154510556749, 5767.423257674199, 5802.0426629605645, 4638.0, 4644.373009643655, 4654.348391225163, 4655.72065676047, 4660.533946605312, 5490.450954904637, 5533.612647114664, 5551.361593416808, 5588.2455868458865, 5616.634918730269, 5302.534321540502, 5327.760250788801, 5334.279872217357, 5400.378956420062, 5427.294451721251, 5313.946860531345, 5393.595480338912, 5453.209284653783, 5453.336732296529, 5476.397596264487, 5123.436421993503, 5370.959035566616, 5424.647397919122, 5428.67427954324, 5431.83704488866, 5423.159410291411, 5426.321709786277, 5436.961139572661, 5477.891011960503, 5508.421615730291, 5225.817096401608, 5365.678059316453, 5377.919331209957, 5381.542568881689, 5388.191771234347], "value": 4352.786639869543, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [836.7824365664925, 880.5773228850152, 911.3529394130171, 925.754675011122, 941.1012483078661, 16336.183190840455, 17637.91181044096, 17667.293308267654, 17674.02792846393, 17686.38097666582, 17091.82908170918, 17805.64388712225, 17831.108444577785, 17845.91077044616, 17851.821481785177, 867.2758246863845, 873.3842640938155, 885.8671199320121, 901.143913282381, 904.6652738486769, 884.5975081338008, 885.8272636835819, 885.9424137431079, 886.2555453419127, 889.0531583863165, 927.7866090799121, 929.5723966975165, 929.6885543342988, 930.1675000874247, 931.3247895275938, 922.580225997173, 923.3444254579257, 924.6024209589874, 925.3060204846365, 925.5834874306555, 680.2823021712092, 680.384252251713, 680.5100327764002, 680.6154522694661, 681.0533358631507, 17018.23417946183, 17178.398756043603, 17275.481735547954, 18573.257069717194, 18726.812731872644, 15081.245937703181, 15712.842871571253, 15880.682386352324, 16125.596860078474, 16430.260638271306, 13590.184588924696, 13682.110662806886, 13890.4443822247, 14028.158310501403, 14330.283485825772, 4111.71218014739, 4123.2165888481095, 4196.769177695225, 4203.453551038376, 4231.640310573605, 89363.55318223432, 89703.55148224282, 89937.0, 92478.0, 93642.53178734063, 12690.0, 12702.237865728086, 12712.110152289435, 12766.106372553832, 13043.804342934864, 22873.88563057174, 22889.542209155723, 23395.06419743191, 23698.407539811644, 23882.52234955291, 9076.183143517115, 9211.631534738535, 9239.538023098818, 9241.861372079424, 9258.444493330422, 4957.705478598492, 4992.500749925051, 5011.774470148852, 5075.670081444694, 5123.3339665843205, 3695.926081478355, 3726.552813662324, 3769.26499332633, 3783.319002579562, 3817.6564109230303, 620.6679426506797, 624.7407325959766, 626.7430353555112, 639.9328070552618, 640.3020707428925, 771.6064806948384, 788.3299195683624, 793.8015496125969, 809.8299357134949, 816.4774544291658, 11308.943455282671, 12126.27242365461, 12171.269723816422, 12383.050898039839, 12433.75132497345, 797.4537441852234, 798.8282519258387, 800.2517646001007, 819.5902048975512, 819.9385046121565, 5659.547236221092, 5682.090865461589, 5751.1085781703905, 5751.281089863767, 5762.567807414461, 81338.0, 82675.58662206771, 83780.0, 85408.0, 87605.56197218974, 60491.0, 75531.62234188794, 75533.0, 77190.0, 77355.0, 65406.0, 67016.0, 67475.0, 67998.3200168002, 69191.65404172947, 10811.405372704578, 10881.18391120685, 11163.0, 11166.218364714392, 11210.215284929976, 44492.55507445014, 44644.77677611591, 44918.77540612276, 45429.0, 45732.31401529046, 51598.74200628973, 51768.22347664713, 51957.0, 52149.7392513035, 54152.0, 12499.127921463027, 12531.310777907303, 12609.432575534307, 12833.230006199658, 12902.225866448043, 14676.119432833637, 14867.330970106155, 15028.17345046033, 15037.022593531823, 15093.0, 14225.573232803032, 14351.210683412513, 14355.784663230268, 14368.209748463934, 14431.639209019859, 13222.404991775586, 13482.730345393233, 13485.595432137052, 13687.589372318846, 13858.792118118035, 14237.359318830884, 14312.928435357757, 14314.570562883131, 14326.0, 14331.85668143347, 10720.946395267974, 10725.731856703645, 10819.837702434626, 11079.50142243585, 11191.608293709682, 11536.36549989725, 11590.188686791844, 11859.0, 11933.641990740292, 12060.457279422271], "value": 7896.684944277916, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 113.0, 115.0, 114.0, 112.0, 113.0, 113.0, 112.0, 113.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 116.0, 157.0, 120.0, 115.0, 116.0, 118.0, 132.0, 114.0], "value": 124.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 160.0, 161.0, 162.0, 161.0, 160.0, 162.0, 162.0, 162.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 112.0, 77.0, 76.0, 76.0, 76.0, 89.0, 75.0], "value": 80.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 103.0, 83.0, 83.0, 83.0, 99.0, 82.0, 82.0, 107.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 147.0, 145.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 56.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 53.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 53.0, 53.0, 52.0, 53.0, 53.0, 53.0, 53.0], "value": 52.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 75.0, 81.0, 75.0, 76.0, 82.0, 80.0, 80.0, 80.0, 85.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 122.0, 129.0, 123.0, 118.0, 128.0, 120.0, 124.0, 155.0, 123.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 159.0, 164.0, 159.0, 165.0, 161.0, 160.0, 159.0, 157.0], "value": 160.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 60.0, 59.0, 59.0, 60.0, 60.0, 66.0, 58.0, 58.0], "value": 60.3, "unit": "score"}]}]} 02:00:21 INFO - Return code: 0 02:00:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:00:21 ERROR - # TBPL SUCCESS # 02:00:21 INFO - Running post-action listener: _resource_record_post_action 02:00:21 INFO - [mozharness: 2017-04-11 09:00:21.599387Z] Finished run-tests step (success) 02:00:21 INFO - Running post-run listener: _resource_record_post_run 02:00:21 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 0; Write bytes: 366297088; Read time: 0; Write time: 258716 02:00:21 INFO - TinderboxPrint: CPU usage
13.1% 02:00:21 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 02:00:21 INFO - TinderboxPrint: I/O write bytes / time
366,297,088 / 258,716 02:00:21 INFO - TinderboxPrint: CPU idle
8,268.8 (86.8%) 02:00:21 INFO - TinderboxPrint: CPU user
1,204.5 (12.7%) 02:00:21 INFO - TinderboxPrint: Swap in / out
0 / 0 02:00:21 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110723072; Read time: 0; Write time: 113452 02:00:21 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 255574016; Read time: 0; Write time: 145264 02:00:22 INFO - Running post-run listener: _upload_blobber_files 02:00:22 INFO - Blob upload gear active. 02:00:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:00:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:00:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:00:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:00:26 INFO - (blobuploader) - INFO - Open directory for files ... 02:00:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:00:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:00:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:29 INFO - (blobuploader) - INFO - Done attempting. 02:00:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:00:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:00:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:34 INFO - (blobuploader) - INFO - Done attempting. 02:00:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:00:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:00:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:39 INFO - (blobuploader) - INFO - Done attempting. 02:00:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:00:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:00:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:00:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:00:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:00:42 INFO - (blobuploader) - INFO - Done attempting. 02:00:42 INFO - (blobuploader) - INFO - Iteration through files over. 02:00:42 INFO - Return code: 0 02:00:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:00:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:00:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2674326bde3413a8ca5a113d2d5297b75ae7319c2dcf38004afe9b12276008a9f7e52239134c604451abebd84ce0fe76c9ec84683c43e21709509b934c0c496e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/18852263733b0ac12191d2d2c797e2c8315672f646d17a961b47306de9901280bb791b5014e47060bdaaa471cfafd3cbb14c57e93ef78ee7d812ab3b01f557c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2a28ff8edc17210c9c7a6c10f8cd6e010767698379bd229400a2cefa75b886ec4ba2b1f74d1cb58af0bdfdf6364dd51b07c26764019db8ae20a95f923ff350e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/300d991db2f54db36a30b3c81f7e2296f7d00565b3a26c7e419fdeabc786d5c8cd8d0e81987ebf059adadfe444b0f71f1d2f70a8150b6b8d1accf97784ba5357"} 02:00:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:00:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:00:42 INFO - Contents: 02:00:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2674326bde3413a8ca5a113d2d5297b75ae7319c2dcf38004afe9b12276008a9f7e52239134c604451abebd84ce0fe76c9ec84683c43e21709509b934c0c496e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/18852263733b0ac12191d2d2c797e2c8315672f646d17a961b47306de9901280bb791b5014e47060bdaaa471cfafd3cbb14c57e93ef78ee7d812ab3b01f557c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2a28ff8edc17210c9c7a6c10f8cd6e010767698379bd229400a2cefa75b886ec4ba2b1f74d1cb58af0bdfdf6364dd51b07c26764019db8ae20a95f923ff350e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/300d991db2f54db36a30b3c81f7e2296f7d00565b3a26c7e419fdeabc786d5c8cd8d0e81987ebf059adadfe444b0f71f1d2f70a8150b6b8d1accf97784ba5357"} 02:00:42 INFO - Running post-run listener: copy_logs_to_upload_dir 02:00:42 INFO - Copying logs to upload dir... 02:00:42 INFO - mkdir: /builds/slave/test/build/upload/logs 02:00:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.588952 ========= master_lag: 9.46 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-04-11 02:00:50.849146) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 02:00:50.853071) ========= 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=51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2674326bde3413a8ca5a113d2d5297b75ae7319c2dcf38004afe9b12276008a9f7e52239134c604451abebd84ce0fe76c9ec84683c43e21709509b934c0c496e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/18852263733b0ac12191d2d2c797e2c8315672f646d17a961b47306de9901280bb791b5014e47060bdaaa471cfafd3cbb14c57e93ef78ee7d812ab3b01f557c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2a28ff8edc17210c9c7a6c10f8cd6e010767698379bd229400a2cefa75b886ec4ba2b1f74d1cb58af0bdfdf6364dd51b07c26764019db8ae20a95f923ff350e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/300d991db2f54db36a30b3c81f7e2296f7d00565b3a26c7e419fdeabc786d5c8cd8d0e81987ebf059adadfe444b0f71f1d2f70a8150b6b8d1accf97784ba5357"} build_url:https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011326 build_url: 'https://queue.taskcluster.net/v1/task/GWCKor95R4G6_k_GxXzP5Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2674326bde3413a8ca5a113d2d5297b75ae7319c2dcf38004afe9b12276008a9f7e52239134c604451abebd84ce0fe76c9ec84683c43e21709509b934c0c496e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/18852263733b0ac12191d2d2c797e2c8315672f646d17a961b47306de9901280bb791b5014e47060bdaaa471cfafd3cbb14c57e93ef78ee7d812ab3b01f557c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/2a28ff8edc17210c9c7a6c10f8cd6e010767698379bd229400a2cefa75b886ec4ba2b1f74d1cb58af0bdfdf6364dd51b07c26764019db8ae20a95f923ff350e6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/300d991db2f54db36a30b3c81f7e2296f7d00565b3a26c7e419fdeabc786d5c8cd8d0e81987ebf059adadfe444b0f71f1d2f70a8150b6b8d1accf97784ba5357"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-11 02:00:50.896199) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:00:50.896531) ========= 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=51fc86400edbde9650d2781a000001d0-1491899697.513323-973595965 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.11 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-11 02:00:51.014461) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 02:00:51.014792) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-11 02:00:51.015073) ========= ========= Total master_lag: 9.67 =========