builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-018 starttime: 1491437730.28 results: success (0) revision: f09f78db26a594b76139cee19a2487f9509d92ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:30.276280) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:30.276800) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:30.277119) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:30.295485) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:30.295838) ========= 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=62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-05 17:15:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2017-04-05 17:15:30 (223 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.122976 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:30.433544) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:30.436749) ========= 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=62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013822 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:30.486647) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-05 17:15:30.487027) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f09f78db26a594b76139cee19a2487f9509d92ee --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 f09f78db26a594b76139cee19a2487f9509d92ee --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=62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-05 17:15:30,835 truncating revision to first 12 chars 2017-04-05 17:15:30,835 Setting DEBUG logging. 2017-04-05 17:15:30,835 attempt 1/10 2017-04-05 17:15:30,835 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f09f78db26a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-05 17:15:32,047 unpacking tar archive at: mozilla-aurora-f09f78db26a5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.482659 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-05 17:15:31.987088) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:31.987886) ========= script_repo_revision: f09f78db26a594b76139cee19a2487f9509d92ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:31.988792) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:31.989474) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 17:15:32.001559) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-04-05 17:15:32.001948) ========= /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=62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:15:32 INFO - MultiFileLogger online at 20170405 17:15:32 in /builds/slave/test 17:15:32 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 17:15:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:15:32 INFO - {'append_to_log': False, 17:15:32 INFO - 'base_work_dir': '/builds/slave/test', 17:15:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 17:15:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:15:32 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 17:15:32 INFO - 'buildbot_json_path': 'buildprops.json', 17:15:32 INFO - 'config_files': ('talos/linux_config.py',), 17:15:32 INFO - 'default_actions': ('clobber', 17:15:32 INFO - 'read-buildbot-config', 17:15:32 INFO - 'download-and-extract', 17:15:32 INFO - 'populate-webroot', 17:15:32 INFO - 'create-virtualenv', 17:15:32 INFO - 'install', 17:15:32 INFO - 'run-tests'), 17:15:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:15:32 INFO - 'download_minidump_stackwalk': True, 17:15:32 INFO - 'download_symbols': 'ondemand', 17:15:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:15:32 INFO - 'tooltool.py': '/tools/tooltool.py', 17:15:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:15:32 INFO - '/tools/misc-python/virtualenv.py')}, 17:15:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:15:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:15:32 INFO - 'gecko_profile': False, 17:15:32 INFO - 'gecko_profile_interval': 0, 17:15:32 INFO - 'installer_path': 'installer.exe', 17:15:32 INFO - 'log_level': 'info', 17:15:32 INFO - 'log_name': 'talos', 17:15:32 INFO - 'log_to_console': True, 17:15:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:15:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:15:32 INFO - 'opt_config_files': (), 17:15:32 INFO - 'pip_index': False, 17:15:32 INFO - 'suite': 'dromaeojs', 17:15:32 INFO - 'system_bits': '32', 17:15:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:15:32 INFO - 'title': 'talos-linux64-ix-018', 17:15:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:15:32 INFO - 'use_talos_json': True, 17:15:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:15:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:15:32 INFO - 'work_dir': 'build'} 17:15:32 INFO - [mozharness: 2017-04-06 00:15:32.448158Z] Running clobber step. 17:15:32 INFO - Running pre-action listener: _resource_record_pre_action 17:15:32 INFO - Running main action method: clobber 17:15:32 INFO - rmtree: /builds/slave/test/build 17:15:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:15:33 INFO - Running post-action listener: _resource_record_post_action 17:15:33 INFO - [mozharness: 2017-04-06 00:15:33.384491Z] Finished clobber step (success) 17:15:33 INFO - [mozharness: 2017-04-06 00:15:33.384591Z] Running read-buildbot-config step. 17:15:33 INFO - Running pre-action listener: _resource_record_pre_action 17:15:33 INFO - Running main action method: read_buildbot_config 17:15:33 INFO - Using buildbot properties: 17:15:33 INFO - { 17:15:33 INFO - "project": "", 17:15:33 INFO - "product": "firefox", 17:15:33 INFO - "who": "dholbert@mozilla.com", 17:15:33 INFO - "installer_path": "public/build/target.tar.bz2", 17:15:33 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:15:33 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 17:15:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 17:15:33 INFO - "stage_platform": "linux64", 17:15:33 INFO - "basedir": "/builds/slave/test", 17:15:33 INFO - "buildnumber": 118, 17:15:33 INFO - "platform": "ubuntu64_hw", 17:15:33 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 17:15:33 INFO - "slavebuilddir": "test", 17:15:33 INFO - "taskId": "Kj9ZtTSbTcK_esaKxxQ3pA", 17:15:33 INFO - "branch": "mozilla-aurora", 17:15:33 INFO - "script_repo_revision": "production", 17:15:33 INFO - "revision": "f09f78db26a594b76139cee19a2487f9509d92ee", 17:15:33 INFO - "slavename": "talos-linux64-ix-018", 17:15:33 INFO - "repo_path": "releases/mozilla-aurora" 17:15:33 INFO - } 17:15:33 INFO - Finding installer, test and symbols from parent task. 17:15:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kj9ZtTSbTcK_esaKxxQ3pA'}, attempt #1 17:15:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kj9ZtTSbTcK_esaKxxQ3pA'}, attempt #1 17:15:34 INFO - Task dependencies: B4B12CEYQP-axvULZf_eHQ 17:15:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ'}, attempt #1 17:15:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.tar.bz2 17:15:34 INFO - Set test_url: https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/test_packages.json 17:15:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.crashreporter-symbols.zip 17:15:34 INFO - Running post-action listener: _resource_record_post_action 17:15:34 INFO - [mozharness: 2017-04-06 00:15:34.770399Z] Finished read-buildbot-config step (success) 17:15:34 INFO - [mozharness: 2017-04-06 00:15:34.770616Z] Running download-and-extract step. 17:15:34 INFO - Running pre-action listener: _resource_record_pre_action 17:15:34 INFO - Running main action method: download_and_extract 17:15:34 INFO - mkdir: /builds/slave/test/build/tests 17:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:34 INFO - trying https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.test_packages.json 17:15:34 INFO - Downloading https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 17:15:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 17:15:35 INFO - Downloaded 896 bytes. 17:15:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 17:15:35 INFO - Using the following test package requirements: 17:15:35 INFO - {u'common': [u'target.common.tests.zip'], 17:15:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 17:15:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 17:15:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 17:15:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 17:15:35 INFO - u'mozbase': [u'target.common.tests.zip'], 17:15:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 17:15:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 17:15:35 INFO - u'web-platform': [u'target.common.tests.zip', 17:15:35 INFO - u'target.web-platform.tests.zip'], 17:15:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 17:15:35 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 17:15:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.common.tests.zip 17:15:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:15:35 INFO - Fetch https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.common.tests.zip into memory 17:15:36 INFO - Expected file size: 59541662 17:15:36 INFO - Obtained file size: 59541662 17:15:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 17:15:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.common.tests.zip 17:15:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 17:15:46 INFO - Fetch https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.common.tests.zip into memory 17:15:47 INFO - Expected file size: 59541662 17:15:47 INFO - Obtained file size: 59541662 17:15:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.talos.tests.zip 17:15:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 17:15:57 INFO - Fetch https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.talos.tests.zip into memory 17:15:58 INFO - Expected file size: 14404438 17:15:58 INFO - Obtained file size: 14404438 17:15:58 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')]} 17:15:58 INFO - trying https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.tar.bz2 17:15:58 INFO - Downloading https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 17:15:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 17:15:59 INFO - Downloaded 66666518 bytes. 17:15:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.tar.bz2 17:15:59 INFO - mkdir: /builds/slave/test/properties 17:15:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:15:59 INFO - Writing to file /builds/slave/test/properties/build_url 17:15:59 INFO - Contents: 17:15:59 INFO - build_url:https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.tar.bz2 17:16:00 INFO - Running post-action listener: _resource_record_post_action 17:16:00 INFO - Running post-action listener: set_extra_try_arguments 17:16:00 INFO - [mozharness: 2017-04-06 00:16:00.000297Z] Finished download-and-extract step (success) 17:16:00 INFO - [mozharness: 2017-04-06 00:16:00.000403Z] Running populate-webroot step. 17:16:00 INFO - Running pre-action listener: _resource_record_pre_action 17:16:00 INFO - Running main action method: populate_webroot 17:16:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:16:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:16:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:16:00 INFO - '--tppagecycles', 17:16:00 INFO - '7'], 17:16:00 INFO - 'tests': ['tsvgm']}}, 17:16:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:16:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:16:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:16:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:16:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:16:00 INFO - 'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:16:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:16:00 INFO - 'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['damp', 'tps']}, 17:16:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:16:00 INFO - 'tests': ['damp', 'tps']}, 17:16:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['dromaeo_dom']}, 17:16:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:16:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['basic_compositor_video']}, 17:16:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:16:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['a11yr', 17:16:00 INFO - 'ts_paint', 17:16:00 INFO - 'tpaint', 17:16:00 INFO - 'sessionrestore', 17:16:00 INFO - 'sessionrestore_no_auto_restore', 17:16:00 INFO - 'tabpaint']}, 17:16:00 INFO - 'other-e10s': {'tests': ['a11yr', 17:16:00 INFO - 'ts_paint', 17:16:00 INFO - 'tpaint', 17:16:00 INFO - 'sessionrestore', 17:16:00 INFO - 'sessionrestore_no_auto_restore', 17:16:00 INFO - 'tabpaint']}, 17:16:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:16:00 INFO - 'ts_paint', 17:16:00 INFO - 'tpaint', 17:16:00 INFO - 'sessionrestore', 17:16:00 INFO - 'sessionrestore_no_auto_restore', 17:16:00 INFO - 'tabpaint']}, 17:16:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:16:00 INFO - 'ts_paint', 17:16:00 INFO - 'tpaint', 17:16:00 INFO - 'sessionrestore', 17:16:00 INFO - 'sessionrestore_no_auto_restore', 17:16:00 INFO - 'tabpaint']}, 17:16:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['a11yr', 17:16:00 INFO - 'ts_paint', 17:16:00 INFO - 'tpaint', 17:16:00 INFO - 'sessionrestore', 17:16:00 INFO - 'sessionrestore_no_auto_restore', 17:16:00 INFO - 'tabpaint']}, 17:16:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['a11yr', 17:16:00 INFO - 'ts_paint', 17:16:00 INFO - 'tpaint', 17:16:00 INFO - 'sessionrestore', 17:16:00 INFO - 'sessionrestore_no_auto_restore', 17:16:00 INFO - 'tabpaint']}, 17:16:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['tsvgx', 17:16:00 INFO - 'tsvgr_opacity', 17:16:00 INFO - 'tart', 17:16:00 INFO - 'tscrollx', 17:16:00 INFO - 'cart', 17:16:00 INFO - 'tsvg_static']}, 17:16:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:16:00 INFO - 'tsvgr_opacity', 17:16:00 INFO - 'tart', 17:16:00 INFO - 'tscrollx', 17:16:00 INFO - 'cart', 17:16:00 INFO - 'tsvg_static']}, 17:16:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:16:00 INFO - 'talos_options': ['--disable-e10s'], 17:16:00 INFO - 'tests': ['tp5o']}, 17:16:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:16:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:16:00 INFO - 'talos_options': ['--disable-e10s', 17:16:00 INFO - '--xperf_path', 17:16:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:00 INFO - 'tests': ['tp5n']}, 17:16:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:16:00 INFO - 'talos_options': ['--xperf_path', 17:16:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:00 INFO - 'tests': ['tp5n']}}, 17:16:00 INFO - 'talos.zip': {'path': '', 17:16:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:16:00 INFO - Running post-action listener: _resource_record_post_action 17:16:00 INFO - [mozharness: 2017-04-06 00:16:00.013703Z] Finished populate-webroot step (success) 17:16:00 INFO - [mozharness: 2017-04-06 00:16:00.013793Z] Running create-virtualenv step. 17:16:00 INFO - Running pre-action listener: _resource_record_pre_action 17:16:00 INFO - Running main action method: create_virtualenv 17:16:00 INFO - Creating virtualenv /builds/slave/test/build/venv 17:16:00 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 17:16:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:16:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:16:00 INFO - Using real prefix '/usr' 17:16:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:16:00 INFO - Installing distribute.............................................................................................................................................................................................done. 17:16:02 INFO - Installing pip.................done. 17:16:02 INFO - Return code: 0 17:16:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:16:02 INFO - Reading from file tmpfile_stdout 17:16:02 INFO - Output received: 17:16:02 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) 17:16:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:16:02 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')]} 17:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:02 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')]} 17:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:02 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 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16:02 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 17:16:02 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 17:16:02 INFO - Using env: {'DISPLAY': ':0', 17:16:02 INFO - 'HOME': '/home/cltbld', 17:16:02 INFO - 'LANG': 'en_US.UTF-8', 17:16:02 INFO - 'LANGUAGE': 'en_US:en', 17:16:02 INFO - 'LOGNAME': 'cltbld', 17:16:02 INFO - 'MAIL': '/var/mail/cltbld', 17:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:02 INFO - 'MOZ_NO_REMOTE': '1', 17:16:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:02 INFO - 'NO_EM_RESTART': '1', 17:16:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:02 INFO - 'PWD': '/builds/slave/test', 17:16:02 INFO - 'SHELL': '/bin/bash', 17:16:02 INFO - 'SHLVL': '1', 17:16:02 INFO - 'TERM': 'linux', 17:16:02 INFO - 'TMOUT': '86400', 17:16:02 INFO - 'USER': 'cltbld', 17:16:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:02 INFO - '_': '/tools/buildbot/bin/python'} 17:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:03 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 17:16:03 INFO - Cleaning up... 17:16:03 INFO - Return code: 0 17:16:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:16:03 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')]} 17:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:03 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')]} 17:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:03 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 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16:03 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 17:16:03 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 17:16:03 INFO - Using env: {'DISPLAY': ':0', 17:16:03 INFO - 'HOME': '/home/cltbld', 17:16:03 INFO - 'LANG': 'en_US.UTF-8', 17:16:03 INFO - 'LANGUAGE': 'en_US:en', 17:16:03 INFO - 'LOGNAME': 'cltbld', 17:16:03 INFO - 'MAIL': '/var/mail/cltbld', 17:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:03 INFO - 'MOZ_NO_REMOTE': '1', 17:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:03 INFO - 'NO_EM_RESTART': '1', 17:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:03 INFO - 'PWD': '/builds/slave/test', 17:16:03 INFO - 'SHELL': '/bin/bash', 17:16:03 INFO - 'SHLVL': '1', 17:16:03 INFO - 'TERM': 'linux', 17:16:03 INFO - 'TMOUT': '86400', 17:16:03 INFO - 'USER': 'cltbld', 17:16:03 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:03 INFO - '_': '/tools/buildbot/bin/python'} 17:16:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:03 INFO - Downloading/unpacking psutil>=3.1.1 17:16:03 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 17:16:03 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 17:16:03 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 17:16:03 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 17:16:03 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 17:16:03 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 17:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:16:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:05 INFO - Installing collected packages: psutil 17:16:05 INFO - Running setup.py install for psutil 17:16:05 INFO - building 'psutil._psutil_linux' extension 17:16:05 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 17:16:05 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 17:16:05 INFO - building 'psutil._psutil_posix' extension 17:16:05 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 17:16:05 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 17:16:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:05 INFO - Successfully installed psutil 17:16:05 INFO - Cleaning up... 17:16:05 INFO - Return code: 0 17:16:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:16:05 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')]} 17:16:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:05 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')]} 17:16:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:05 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 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16:05 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 17:16:05 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 17:16:05 INFO - Using env: {'DISPLAY': ':0', 17:16:05 INFO - 'HOME': '/home/cltbld', 17:16:05 INFO - 'LANG': 'en_US.UTF-8', 17:16:05 INFO - 'LANGUAGE': 'en_US:en', 17:16:05 INFO - 'LOGNAME': 'cltbld', 17:16:05 INFO - 'MAIL': '/var/mail/cltbld', 17:16:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:05 INFO - 'MOZ_NO_REMOTE': '1', 17:16:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:05 INFO - 'NO_EM_RESTART': '1', 17:16:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:05 INFO - 'PWD': '/builds/slave/test', 17:16:05 INFO - 'SHELL': '/bin/bash', 17:16:05 INFO - 'SHLVL': '1', 17:16:05 INFO - 'TERM': 'linux', 17:16:05 INFO - 'TMOUT': '86400', 17:16:05 INFO - 'USER': 'cltbld', 17:16:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:05 INFO - '_': '/tools/buildbot/bin/python'} 17:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:16:06 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 17:16:06 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 17:16:06 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 17:16:06 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 17:16:06 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 17:16:06 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 17:16:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:16:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:16:08 INFO - Installing collected packages: mozsystemmonitor 17:16:08 INFO - Running setup.py install for mozsystemmonitor 17:16:08 INFO - Successfully installed mozsystemmonitor 17:16:08 INFO - Cleaning up... 17:16:08 INFO - Return code: 0 17:16:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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', '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 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:16: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 jsonschema==2.5.1 17:16:08 INFO - Using env: {'DISPLAY': ':0', 17:16:08 INFO - 'HOME': '/home/cltbld', 17:16:08 INFO - 'LANG': 'en_US.UTF-8', 17:16:08 INFO - 'LANGUAGE': 'en_US:en', 17:16:08 INFO - 'LOGNAME': 'cltbld', 17:16:08 INFO - 'MAIL': '/var/mail/cltbld', 17:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:08 INFO - 'MOZ_NO_REMOTE': '1', 17:16:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:08 INFO - 'NO_EM_RESTART': '1', 17:16:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:08 INFO - 'PWD': '/builds/slave/test', 17:16:08 INFO - 'SHELL': '/bin/bash', 17:16:08 INFO - 'SHLVL': '1', 17:16:08 INFO - 'TERM': 'linux', 17:16:08 INFO - 'TMOUT': '86400', 17:16:08 INFO - 'USER': 'cltbld', 17:16:08 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:08 INFO - '_': '/tools/buildbot/bin/python'} 17:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:08 INFO - Downloading/unpacking jsonschema==2.5.1 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:16:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:16:10 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 17:16:10 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 17:16:10 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 17:16:10 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 17:16:10 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 17:16:10 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 17:16:10 INFO - Downloading functools32-3.2.3-2.tar.gz 17:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:16:10 INFO - warning: no files found matching '*.txt' 17:16:10 INFO - no previously-included directories found matching 'build' 17:16:10 INFO - no previously-included directories found matching 'dist' 17:16:10 INFO - no previously-included directories found matching '.git*' 17:16:10 INFO - Installing collected packages: jsonschema, functools32 17:16:11 INFO - Running setup.py install for functools32 17:16:11 INFO - warning: no files found matching '*.txt' 17:16:11 INFO - no previously-included directories found matching 'build' 17:16:11 INFO - no previously-included directories found matching 'dist' 17:16:11 INFO - no previously-included directories found matching '.git*' 17:16:11 INFO - Successfully installed jsonschema functools32 17:16:11 INFO - Cleaning up... 17:16:11 INFO - Return code: 0 17:16:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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', '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 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:16: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 functools32==3.2.3-2 17:16:11 INFO - Using env: {'DISPLAY': ':0', 17:16:11 INFO - 'HOME': '/home/cltbld', 17:16:11 INFO - 'LANG': 'en_US.UTF-8', 17:16:11 INFO - 'LANGUAGE': 'en_US:en', 17:16:11 INFO - 'LOGNAME': 'cltbld', 17:16:11 INFO - 'MAIL': '/var/mail/cltbld', 17:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:11 INFO - 'MOZ_NO_REMOTE': '1', 17:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:11 INFO - 'NO_EM_RESTART': '1', 17:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:11 INFO - 'PWD': '/builds/slave/test', 17:16:11 INFO - 'SHELL': '/bin/bash', 17:16:11 INFO - 'SHLVL': '1', 17:16:11 INFO - 'TERM': 'linux', 17:16:11 INFO - 'TMOUT': '86400', 17:16:11 INFO - 'USER': 'cltbld', 17:16:11 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:11 INFO - '_': '/tools/buildbot/bin/python'} 17:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:16:11 INFO - Cleaning up... 17:16:11 INFO - Return code: 0 17:16:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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', '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 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:16: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 blobuploader==1.2.4 17:16:11 INFO - Using env: {'DISPLAY': ':0', 17:16:11 INFO - 'HOME': '/home/cltbld', 17:16:11 INFO - 'LANG': 'en_US.UTF-8', 17:16:11 INFO - 'LANGUAGE': 'en_US:en', 17:16:11 INFO - 'LOGNAME': 'cltbld', 17:16:11 INFO - 'MAIL': '/var/mail/cltbld', 17:16:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:11 INFO - 'MOZ_NO_REMOTE': '1', 17:16:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:11 INFO - 'NO_EM_RESTART': '1', 17:16:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:11 INFO - 'PWD': '/builds/slave/test', 17:16:11 INFO - 'SHELL': '/bin/bash', 17:16:11 INFO - 'SHLVL': '1', 17:16:11 INFO - 'TERM': 'linux', 17:16:11 INFO - 'TMOUT': '86400', 17:16:11 INFO - 'USER': 'cltbld', 17:16:11 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:11 INFO - '_': '/tools/buildbot/bin/python'} 17:16:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:11 INFO - Downloading/unpacking blobuploader==1.2.4 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16:13 INFO - Downloading blobuploader-1.2.4.tar.gz 17:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:16:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:16:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16:14 INFO - Downloading docopt-0.6.1.tar.gz 17:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:16:14 INFO - Installing collected packages: blobuploader, requests, docopt 17:16:14 INFO - Running setup.py install for blobuploader 17:16:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:16:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:16:14 INFO - Running setup.py install for requests 17:16:14 INFO - Running setup.py install for docopt 17:16:14 INFO - Successfully installed blobuploader requests docopt 17:16:14 INFO - Cleaning up... 17:16:15 INFO - Return code: 0 17:16:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:15 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 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16:15 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 17:16:15 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 17:16:15 INFO - Using env: {'DISPLAY': ':0', 17:16:15 INFO - 'HOME': '/home/cltbld', 17:16:15 INFO - 'LANG': 'en_US.UTF-8', 17:16:15 INFO - 'LANGUAGE': 'en_US:en', 17:16:15 INFO - 'LOGNAME': 'cltbld', 17:16:15 INFO - 'MAIL': '/var/mail/cltbld', 17:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:15 INFO - 'MOZ_NO_REMOTE': '1', 17:16:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:15 INFO - 'NO_EM_RESTART': '1', 17:16:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:15 INFO - 'PWD': '/builds/slave/test', 17:16:15 INFO - 'SHELL': '/bin/bash', 17:16:15 INFO - 'SHLVL': '1', 17:16:15 INFO - 'TERM': 'linux', 17:16:15 INFO - 'TMOUT': '86400', 17:16:15 INFO - 'USER': 'cltbld', 17:16:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:15 INFO - '_': '/tools/buildbot/bin/python'} 17:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:15 INFO - Running setup.py (path:/tmp/pip-igIk6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:15 INFO - Running setup.py (path:/tmp/pip-DAqP86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:15 INFO - Running setup.py (path:/tmp/pip-bMFQg9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:15 INFO - Running setup.py (path:/tmp/pip-98hZ8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:15 INFO - Running setup.py (path:/tmp/pip-RHaaHH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:15 INFO - Running setup.py (path:/tmp/pip-e2GH_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:15 INFO - Running setup.py (path:/tmp/pip-Bgc14e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:15 INFO - Running setup.py (path:/tmp/pip-oGFdtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:15 INFO - Running setup.py (path:/tmp/pip-2ysU_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:15 INFO - Running setup.py (path:/tmp/pip-zdzOO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:15 INFO - Running setup.py (path:/tmp/pip-0Z7Jsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:16 INFO - Running setup.py (path:/tmp/pip-BpbTM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:16 INFO - Running setup.py (path:/tmp/pip-FUplko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:16 INFO - Running setup.py (path:/tmp/pip-DuAhtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:16 INFO - Running setup.py (path:/tmp/pip-Kj3nYr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:16 INFO - Running setup.py (path:/tmp/pip-AKQ3_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:16 INFO - Running setup.py (path:/tmp/pip-5OcDNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:16:16 INFO - Running setup.py install for manifestparser 17:16:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:16:16 INFO - Running setup.py install for mozcrash 17:16:16 INFO - Running setup.py install for mozdebug 17:16:16 INFO - Running setup.py install for mozdevice 17:16:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:16:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:16:16 INFO - Running setup.py install for mozfile 17:16:17 INFO - Running setup.py install for mozhttpd 17:16:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for mozinfo 17:16:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for mozInstall 17:16:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for mozleak 17:16:17 INFO - Running setup.py install for mozlog 17:16:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for moznetwork 17:16:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for mozprocess 17:16:17 INFO - Running setup.py install for mozprofile 17:16:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for mozrunner 17:16:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for mozscreenshot 17:16:18 INFO - Running setup.py install for moztest 17:16:18 INFO - Running setup.py install for mozversion 17:16:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:16:18 INFO - Cleaning up... 17:16:18 INFO - Return code: 0 17:16:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:18 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 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16:18 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 17:16:18 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 17:16:18 INFO - Using env: {'DISPLAY': ':0', 17:16:18 INFO - 'HOME': '/home/cltbld', 17:16:18 INFO - 'LANG': 'en_US.UTF-8', 17:16:18 INFO - 'LANGUAGE': 'en_US:en', 17:16:18 INFO - 'LOGNAME': 'cltbld', 17:16:18 INFO - 'MAIL': '/var/mail/cltbld', 17:16:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:18 INFO - 'MOZ_NO_REMOTE': '1', 17:16:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:18 INFO - 'NO_EM_RESTART': '1', 17:16:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:18 INFO - 'PWD': '/builds/slave/test', 17:16:18 INFO - 'SHELL': '/bin/bash', 17:16:18 INFO - 'SHLVL': '1', 17:16:18 INFO - 'TERM': 'linux', 17:16:18 INFO - 'TMOUT': '86400', 17:16:18 INFO - 'USER': 'cltbld', 17:16:18 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:18 INFO - '_': '/tools/buildbot/bin/python'} 17:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:18 INFO - Running setup.py (path:/tmp/pip-bYKf2K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:18 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)) 17:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:18 INFO - Running setup.py (path:/tmp/pip-TuMwo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:18 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)) 17:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:18 INFO - Running setup.py (path:/tmp/pip-HHGhG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:18 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)) 17:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:18 INFO - Running setup.py (path:/tmp/pip-6_Zqfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:18 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)) 17:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:18 INFO - Running setup.py (path:/tmp/pip-5vFplu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:18 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)) 17:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:18 INFO - Running setup.py (path:/tmp/pip-zHOJxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:18 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)) 17:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:18 INFO - Running setup.py (path:/tmp/pip-Qc75em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:19 INFO - Running setup.py (path:/tmp/pip-O7af6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:19 INFO - Running setup.py (path:/tmp/pip-zLwEC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:19 INFO - Running setup.py (path:/tmp/pip-EgPVxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:19 INFO - Running setup.py (path:/tmp/pip-D7gvx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:19 INFO - Running setup.py (path:/tmp/pip-Wp82f7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:19 INFO - Running setup.py (path:/tmp/pip-i1uqP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:19 INFO - Running setup.py (path:/tmp/pip-haK49u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:19 INFO - Running setup.py (path:/tmp/pip-xZLstD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:19 INFO - Running setup.py (path:/tmp/pip-XzOuwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:19 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)) 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:19 INFO - Running setup.py (path:/tmp/pip-2JHnsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:19 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)) 17:16:19 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)) 17:16:19 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)) 17:16:19 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)) 17:16:19 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)) 17:16:19 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)) 17:16:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16:21 INFO - Downloading blessings-1.6.tar.gz 17:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:16:21 INFO - Installing collected packages: blessings 17:16:21 INFO - Running setup.py install for blessings 17:16:21 INFO - Successfully installed blessings 17:16:21 INFO - Cleaning up... 17:16:22 INFO - Return code: 0 17:16:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:22 INFO - Reading from file tmpfile_stdout 17:16:22 INFO - Current package versions: 17:16:22 INFO - argparse == 1.2.1 17:16:22 INFO - blessings == 1.6 17:16:22 INFO - blobuploader == 1.2.4 17:16:22 INFO - docopt == 0.6.1 17:16:22 INFO - functools32 == 3.2.3-2 17:16:22 INFO - jsonschema == 2.5.1 17:16:22 INFO - manifestparser == 1.1 17:16:22 INFO - mozInstall == 1.12 17:16:22 INFO - mozcrash == 1.0 17:16:22 INFO - mozdebug == 0.1 17:16:22 INFO - mozdevice == 0.49 17:16:22 INFO - mozfile == 1.2 17:16:22 INFO - mozhttpd == 0.7 17:16:22 INFO - mozinfo == 0.9 17:16:22 INFO - mozleak == 0.1 17:16:22 INFO - mozlog == 3.4 17:16:22 INFO - moznetwork == 0.27 17:16:22 INFO - mozprocess == 0.25 17:16:22 INFO - mozprofile == 0.28 17:16:22 INFO - mozrunner == 6.13 17:16:22 INFO - mozscreenshot == 0.1 17:16:22 INFO - mozsystemmonitor == 0.3 17:16:22 INFO - moztest == 0.8 17:16:22 INFO - mozversion == 1.4 17:16:22 INFO - psutil == 3.1.1 17:16:22 INFO - requests == 1.2.3 17:16:22 INFO - wsgiref == 0.1.2 17:16:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:22 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 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16:22 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 17:16:22 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 17:16:22 INFO - Using env: {'DISPLAY': ':0', 17:16:22 INFO - 'HOME': '/home/cltbld', 17:16:22 INFO - 'LANG': 'en_US.UTF-8', 17:16:22 INFO - 'LANGUAGE': 'en_US:en', 17:16:22 INFO - 'LOGNAME': 'cltbld', 17:16:22 INFO - 'MAIL': '/var/mail/cltbld', 17:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:22 INFO - 'MOZ_NO_REMOTE': '1', 17:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:22 INFO - 'NO_EM_RESTART': '1', 17:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:22 INFO - 'PWD': '/builds/slave/test', 17:16:22 INFO - 'SHELL': '/bin/bash', 17:16:22 INFO - 'SHLVL': '1', 17:16:22 INFO - 'TERM': 'linux', 17:16:22 INFO - 'TMOUT': '86400', 17:16:22 INFO - 'USER': 'cltbld', 17:16:22 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:22 INFO - '_': '/tools/buildbot/bin/python'} 17:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:22 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)) 17:16:22 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)) 17:16:22 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)) 17:16:22 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)) 17:16:22 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)) 17:16:22 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)) 17:16:22 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)) 17:16:22 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)) 17:16:22 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)) 17:16:22 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)) 17:16:22 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)) 17:16:22 INFO - Cleaning up... 17:16:22 INFO - Return code: 0 17:16:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x260e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28ac310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, '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': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', '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 17:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:16:22 INFO - Using env: {'DISPLAY': ':0', 17:16:22 INFO - 'HOME': '/home/cltbld', 17:16:22 INFO - 'LANG': 'en_US.UTF-8', 17:16:22 INFO - 'LANGUAGE': 'en_US:en', 17:16:22 INFO - 'LOGNAME': 'cltbld', 17:16:22 INFO - 'MAIL': '/var/mail/cltbld', 17:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:22 INFO - 'MOZ_NO_REMOTE': '1', 17:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:22 INFO - 'NO_EM_RESTART': '1', 17:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:22 INFO - 'PWD': '/builds/slave/test', 17:16:22 INFO - 'SHELL': '/bin/bash', 17:16:22 INFO - 'SHLVL': '1', 17:16:22 INFO - 'TERM': 'linux', 17:16:22 INFO - 'TMOUT': '86400', 17:16:22 INFO - 'USER': 'cltbld', 17:16:22 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:22 INFO - '_': '/tools/buildbot/bin/python'} 17:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:16:22 INFO - Cleaning up... 17:16:22 INFO - Return code: 0 17:16:22 INFO - Running post-action listener: _resource_record_post_action 17:16:22 INFO - Running post-action listener: _start_resource_monitoring 17:16:22 INFO - Starting resource monitoring. 17:16:22 INFO - [mozharness: 2017-04-06 00:16:22.728475Z] Finished create-virtualenv step (success) 17:16:22 INFO - [mozharness: 2017-04-06 00:16:22.728942Z] Running install step. 17:16:22 INFO - Running pre-action listener: _resource_record_pre_action 17:16:22 INFO - Running main action method: install 17:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:22 INFO - Reading from file tmpfile_stdout 17:16:22 INFO - Detecting whether we're running mozinstall >=1.0... 17:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:16:23 INFO - Reading from file tmpfile_stdout 17:16:23 INFO - Output received: 17:16:23 INFO - Usage: mozinstall [options] installer 17:16:23 INFO - Options: 17:16:23 INFO - -h, --help show this help message and exit 17:16:23 INFO - -d DEST, --destination=DEST 17:16:23 INFO - Directory to install application into. [default: 17:16:23 INFO - "/builds/slave/test"] 17:16:23 INFO - --app=APP Application being installed. [default: firefox] 17:16:23 INFO - mkdir: /builds/slave/test/build/application 17:16:23 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'] 17:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 17:16:39 INFO - Reading from file tmpfile_stdout 17:16:39 INFO - Output received: 17:16:39 INFO - /builds/slave/test/build/application/firefox/firefox 17:16:39 INFO - Running post-action listener: _resource_record_post_action 17:16:39 INFO - [mozharness: 2017-04-06 00:16:39.560010Z] Finished install step (success) 17:16:39 INFO - [mozharness: 2017-04-06 00:16:39.560340Z] Running run-tests step. 17:16:39 INFO - Running pre-action listener: _resource_record_pre_action 17:16:39 INFO - Running main action method: run_tests 17:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:16:39 INFO - Python 2.7.3 17:16:39 INFO - Return code: 0 17:16:39 INFO - grabbing minidump binary from tooltool 17:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:39 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 0x28a9400>, '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 0x28aa3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28aa840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:16:39 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 17:16:39 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 17:16:39 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 17:16:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:16:39 INFO - Return code: 0 17:16:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:16:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:16:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:16:39 INFO - ENV: RUST_BACKTRACE is now 1 17:16:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:16:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:16:39 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/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 17:16:39 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/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 17:16:39 INFO - Using env: {'DISPLAY': ':0', 17:16:39 INFO - 'HOME': '/home/cltbld', 17:16:39 INFO - 'LANG': 'en_US.UTF-8', 17:16:39 INFO - 'LANGUAGE': 'en_US:en', 17:16:39 INFO - 'LOGNAME': 'cltbld', 17:16:39 INFO - 'MAIL': '/var/mail/cltbld', 17:16:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:16:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:39 INFO - 'MOZ_NO_REMOTE': '1', 17:16:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:16:39 INFO - 'NO_EM_RESTART': '1', 17:16:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:39 INFO - 'PWD': '/builds/slave/test', 17:16:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:16:39 INFO - 'RUST_BACKTRACE': '1', 17:16:39 INFO - 'SHELL': '/bin/bash', 17:16:39 INFO - 'SHLVL': '1', 17:16:39 INFO - 'TERM': 'linux', 17:16:39 INFO - 'TMOUT': '86400', 17:16:39 INFO - 'USER': 'cltbld', 17:16:39 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642', 17:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:39 INFO - '_': '/tools/buildbot/bin/python'} 17:16:39 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/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 17:16:39 INFO - mozversion application_buildid: 20170405232316 17:16:39 INFO - mozversion application_changeset: f09f78db26a594b76139cee19a2487f9509d92ee 17:16:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:16:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:16:39 INFO - mozversion application_name: Firefox 17:16:39 INFO - mozversion application_remotingname: firefox-dev 17:16:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:16:39 INFO - mozversion application_vendor: Mozilla 17:16:39 INFO - mozversion application_version: 54.0a2 17:16:39 INFO - mozversion platform_buildid: 20170405232316 17:16:39 INFO - mozversion platform_changeset: f09f78db26a594b76139cee19a2487f9509d92ee 17:16:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:16:39 INFO - mozversion platform_version: 54.0a2 17:16:39 INFO - using testdate: 1491437799 17:16:39 INFO - actual date: 1491437799 17:16:39 INFO - starting webserver on 'localhost:52834' 17:16:39 INFO - SUITE-START | Running 2 tests 17:16:39 INFO - TEST-START | dromaeo_css 17:16:39 INFO - operating with platform_type : linux_ 17:16:39 INFO - Initialising browser for dromaeo_css test... 17:16:39 INFO - TEST-INFO | started process 6330 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyS8Lnb/profile http://localhost:52834/getInfo.html) 17:16:43 INFO - PROCESS | 6330 | __metrics Screen width/height:1600/1200 17:16:43 INFO - PROCESS | 6330 | colorDepth:24 17:16:43 INFO - PROCESS | 6330 | Browser inner width/height: 1024/697 17:16:43 INFO - PROCESS | 6330 | __metrics 17:16:44 INFO - PROCESS | 6330 | 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" 17:16:44 INFO - PROCESS | 6330 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:16:44 INFO - PROCESS | 6330 | console.error: PushService: 17:16:44 INFO - PROCESS | 6330 | stateChangeProcessEnqueue: Error transitioning state 17:16:44 INFO - PROCESS | 6330 | UnknownError 17:16:45 INFO - TEST-INFO | 6330: exit 0 17:16:45 INFO - Browser initialized. 17:16:45 INFO - Running cycle 1/1 for dromaeo_css test... 17:16:45 INFO - TEST-INFO | started process 6422 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyS8Lnb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:16:57 INFO - PROCESS | 6422 | RSS: Main: 139808768 17:16:57 INFO - PROCESS | 6422 | 17:19:58 INFO - PROCESS | 6422 | Cycle 1(1): loaded http://localhost:52834/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52834/tests/dromaeo/cssquery-ext.html) 17:20:00 INFO - PROCESS | 6422 | RSS: Main: 275660800 17:20:00 INFO - PROCESS | 6422 | 17:21:47 INFO - PROCESS | 6422 | ************************* 17:21:47 INFO - PROCESS | 6422 | A coding exception was thrown and uncaught in a Task. 17:21:47 INFO - PROCESS | 6422 | 17:21:47 ERROR - PROCESS | 6422 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:21:47 INFO - PROCESS | 6422 | Full stack: 17:21:47 INFO - PROCESS | 6422 | ************************* 17:21:47 INFO - PROCESS | 6422 | ************************* 17:21:47 INFO - PROCESS | 6422 | A coding exception was thrown and uncaught in a Task. 17:21:47 INFO - PROCESS | 6422 | 17:21:47 ERROR - PROCESS | 6422 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:21:47 INFO - PROCESS | 6422 | Full stack: 17:21:47 INFO - PROCESS | 6422 | ************************* 17:21:47 INFO - PROCESS | 6422 | ************************* 17:21:47 INFO - PROCESS | 6422 | A coding exception was thrown and uncaught in a Task. 17:21:47 INFO - PROCESS | 6422 | 17:21:47 ERROR - PROCESS | 6422 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:21:47 INFO - PROCESS | 6422 | Full stack: 17:21:47 INFO - PROCESS | 6422 | ************************* 17:21:47 INFO - PROCESS | 6422 | ************************* 17:21:47 INFO - PROCESS | 6422 | A coding exception was thrown and uncaught in a Task. 17:21:47 INFO - PROCESS | 6422 | 17:21:47 ERROR - PROCESS | 6422 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:21:47 INFO - PROCESS | 6422 | Full stack: 17:21:47 INFO - PROCESS | 6422 | ************************* 17:23:02 INFO - PROCESS | 6422 | Cycle 1(1): loaded http://localhost:52834/tests/dromaeo/cssquery-ext.html (next: http://localhost:52834/tests/dromaeo/cssquery-jquery.html) 17:23:02 INFO - PROCESS | 6422 | RSS: Main: 227131392 17:23:02 INFO - PROCESS | 6422 | 17:26:04 INFO - PROCESS | 6422 | Cycle 1(1): loaded http://localhost:52834/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52834/tests/dromaeo/cssquery-mootools.html) 17:26:05 INFO - PROCESS | 6422 | RSS: Main: 171687936 17:26:05 INFO - PROCESS | 6422 | 17:29:07 INFO - PROCESS | 6422 | Cycle 1(1): loaded http://localhost:52834/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52834/tests/dromaeo/cssquery-prototype.html) 17:29:07 INFO - PROCESS | 6422 | RSS: Main: 174800896 17:29:07 INFO - PROCESS | 6422 | 17:32:10 INFO - PROCESS | 6422 | Cycle 1(1): loaded http://localhost:52834/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52834/tests/dromaeo/cssquery-yui.html) 17:32:10 INFO - PROCESS | 6422 | RSS: Main: 177242112 17:32:10 INFO - PROCESS | 6422 | 17:35:13 INFO - PROCESS | 6422 | Cycle 1(1): loaded http://localhost:52834/tests/dromaeo/cssquery-yui.html (next: http://localhost:52834/tests/dromaeo/cssquery-dojo.html) 17:35:13 INFO - PROCESS | 6422 | RSS: Main: 264187904 17:35:13 INFO - PROCESS | 6422 | 17:35:13 INFO - PROCESS | 6422 | __start_tp_report 17:35:13 INFO - PROCESS | 6422 | _x_x_mozilla_page_load 17:35:13 INFO - PROCESS | 6422 | _x_x_mozilla_page_load_details 17:35:13 INFO - PROCESS | 6422 | |i|pagename|runs| 17:35:13 INFO - PROCESS | 6422 | |0;dojo.html;3362.798232106074;3365.7980521168715;3397.4394224952875;3409.5397121388605;3441.2945346204015;5764.135379693048;5803.042497987837;5821.825345239639;5829.825105246844;5847.824565263044;5814.1278808178795;5865.853353666161;5867.149263356821;5875.118732190173;5894.64632122073;5207.869803254911;5273.630845840793;5311.442298558634;5344.893102137955;5345.224942383352;5080.542751152396;5105.719185444799;5370.489803468664;5386.703731294777;5387.1111266640955;5249.028929648009;5271.683698978054;5315.255864179018;5333.066713325172;5356.196570514435;5354.464553544653;5357.142857142878;5360.115580929142;5368.463153684639;5381.596380271456;5096.210087436453;5222.4255331913455;5269.973650131763;5329.653572517774;5339.813106541253;5629.7185140742795;5694.77220911163;5773.047447171211;5785.537157027427;5801.941980580183;6265.154204182422;6273.87452250957;6274.56078074535;6311.842203944892;6321.399467050623;6338;6360.0459931010255;6367.395097465734;6371.39471750183;6382.840428989312;5633.690147041911;5651.660900345952;5793.797217097382;5823.417658234227;5840.532757379485;6393.136926514954;6405.679716014274;6410.391012853772;6410.551261411656;6416.615003099736;6144.5084393248435;6169.198004259507;6215.844603884939;6218.906716399258;6232.407921247474;6005.879882402328;6006.12911127884;6025.849353766191;6156.26124865019;6309.652969086745;6216.160818289563;6220.03589443637;6233.812985610439;6234.470070044006;6252.280987686383;5967.46292833647;6000.789972350947;6005.249343832021;6124.601900876429;6277.780277690259;4773.928391074137;4776.761161941889;4794.328793968847;4795.712257264575;4797.832075877328;4234.343676730112;4244.469441319835;4258.936115958263;4272.124214536013;4284.592963668447;5715.514181294553;5802.071668533014;5925;5987.9101813472835;6068.575199736064;6306.842328941813;6321.715522801485;6324.873502529924;6330.8100756977365;6358.77744278948;6089.969550152221;6099.9390006099375;6105.8168254952425;6110.633361998294;6112.480439162721;5121.795128194831;5139;5285.180796976475;5324.227986941872;5389.757460914268;6579.934200658028;6592.538522303393;6597.670116494252;6598.505112116706;6604.8348791280605;6251.78118765841;6261;6270.059491076375;6313.810585682437;6328.873422531431;6193.1329613853195;6256.311805701278;6462.289148193601;6610.70251838678;6626.27111017778;6227.19046523949;6245.750169993149;6259.593126446766;6261.092141639346;6270.49836013127;6354.809355719336;6359.17330747;6361.14124593174;6364.77723279683;6368.9044664330995;5886.499647529837;5905.4685078343155;6134.754609815557;6172.814815555541;6191.318954915046;6092.543059270483;6112.969435152796;6154.784582539769;6204.348543402878;6205.782797602063;6154.984427569401;6184.072389141665;6184.969075154595;6185.628862268279;6189;5839.795607153729;5870;5998.7300571473515;6175.69121543912;6186.133941248138;5981.551383646157;6053.75784968614;6078.756849726138;6139.079138129317;6140.355262697352 17:35:13 INFO - PROCESS | 6422 | |1;ext.html;761.2197497564995;828.4532208742231;846.9364797640177;920.9033051529585;934.3506263147116;30891;42733.35899961503;43338.56661433389;47049;47246.291305630446;44501.554984450144;44787.77606111965;44812.77593612028;44875.32687009697;44900.77549612256;7811.336036436881;7974.441789074767;8961.193492585666;9066.727998160066;9076.773080673003;10521.68434946949;10728;10729.624463143791;10730.839037414446;10733.08768754653;10900.291481053746;10922.781544369107;10937.125029997582;10937.125029997582;10946.507407166697;10848.457577121151;10850.077743391781;10867.891321086767;10876.728081797939;10891;10609.681709548726;10652.840207396817;10663.253572249945;10663.306885052447;10667.626633067805;41211.7939410304;41436.58563414388;41476;41568.584314156775;41893.79053104745;4267.509236437819;4307.5261721210645;4353.0423306872435;4434.711743736679;4443.2224360736745;29432.8528357359;29642.85178574115;29856.8507157465;30350.089497315115;31231;4326.935095973563;4451.666125040635;4458.487273963503;4495.57292057254;4495.865124046284;240567;241854;242154;242555;242567;53162;53217.46782532125;53225.46774532283;53228.467715323124;53321.7333913328;54903.17645235325;54955.45044549504;54969.72515137479;55115.44884551183;55278.72360638252;16426.260818263207;16787.076710781024;16787.496375108767;16831;16832.326706931584;13099.803502947463;13329.66675833112;14562.708745825024;15029.323680434407;15060.096394216382;12778;12893.097483176276;12922.353882306035;12938.611841644766;13043.543475978295;5263.894722105613;5269.973650131801;5271.499207575274;5387.272718182995;5436.70098144598;1001.8897921228659;1009.7374682582581;1016.2733645443543;1020.5407566595062;1021.5556233038652;28003.29991750182;28195.43609127806;28541.57187642187;29284.853575731984;30250.69749302523;1175.5709166154293;1179.204037274836;1181.3030312115893;1187.2994932989404;1193.7194759231575;14608;14628.926855365655;14655.413783448543;14796.408143674133;14826.9046478197;329866;331278;331893;331955;332555;46806.063878722234;46818.53181468142;46850.53149468462;46865;46977.530224698;1477.7340078785708;1486.6655002624539;1509.4415066425493;1524.5045360257961;1549.7907782449452;24890.377740556632;26049.479010419305;26289;26416;26427;36024.639753602125;36100.27799443944;36158.638413615525;36173.638263617024;36220.27559448744;285802;288489;289166;289756;290380;25761.098361557255;25970;28109.859450703432;30291.394172115994;31238.21904452316;39265.411018835315;40358.39462408007;43272.13455730805;43315.56684433115;43720.78139609282;30855.2286192847;31038.68961310358;31325.843370783;31518;31661.20846978844;31007.379852403275;31167.68832311648;31336;31345.52981705322;31713.20716982094;34775.826120870246;35232.11919702028;35308.47037294391;35580;35810.462843056855;30039.249018774706;30173.54739678863;30562.235944101576;30913.381732365677;31145.8442707785;24087.75912240855;24817;24957.251282461555;25093.74906250914;25353.619695704947 17:35:13 INFO - PROCESS | 6422 | |2;jquery.html;421.91139860629266;441.7614488176385;451.3455489540166;470.3368250766419;490.1128956588575;19386.41840744778;19929.60140797185;20421.795782042176;20548;21396.89301553492;20184.495387615316;21745.67381489279;22084.779152208474;22258.777412225914;22448.66327005096;6049.939500605004;6371.171747672807;6620.602763834161;6675.566088204276;6678.933210667904;7680.807979800522;7724.304812566867;7773.961130194341;7784.688612455495;7785.766427007171;7290.27097290272;7566.394688424913;7575.696972121109;7588.27911348421;7809.804754881116;7540.095188577406;7704;7706.884396734054;7753.6123193840085;7799.142094369615;7338.926610733878;7565.773026809149;7574.621268936587;7577.090749110087;7587.81030474237;24382.268531943893;24749.13378031778;24767.87616061926;24970.5005899881;25357.366065848317;3129.264622813637;3135.357251763383;3163.2724473371254;3165.7942233754734;3571.178628915338;16220.51338459848;16327.265273062741;16569.4200702976;16579.336826526924;16579.6684066318;2238.3844442778204;2253.752087270399;2296.2307626944994;2315.8731736407944;2327.1156960354956;114985.4250728741;115565;115781;117727.41136294349;118754.40622796917;17621;17932.91033544824;18104.913705177732;18316.633667326845;18561.81438185628;32333.51499727506;32390.35219295601;32493.675063249066;32552;32557.837210813796;10152.289339746147;10249.97500249984;10261.846072308921;10342.586296548054;10388.740281492872;8622.611982460805;8648.1784230498;8711.303095752324;8761.518116503526;8833.867491987625;9127.771805704777;9132.81734365309;9246.398984066149;9410.341276110606;9418.670346537838;3479.7912125272564;3728.6374941318722;3738.5887552369218;3783.886483405502;3823.4545790159527;926.420986883198;933.4306073295285;935.0696057422907;940.9153176214173;944.9574769135405;19572.314968976018;20589.485262868548;21373.893130534558;21493.355199344045;21581;933.85992101184;960.9183219426287;961.6730311693917;973.3284034016506;974.7563109222694;10987.945060274807;11274.210805243554;11411.48648310828;11472.713182170346;11580.131490138274;651254;662505;667032;672243.7490312947;674581;67074;73010.2698973014;78516.60741696255;112218.43890780494;117096;1218.4029825385496;1231.1259006105765;1243.421808858885;1254.203580726227;1262.450833887262;16784.58038549046;18090.522560692367;18557;18907.62184756325;19088.809111909257;51063;51260.74369628128;51286.230706538685;51983.220251697014;52728.736356317975;64907.35092649013;65798;65878;66989;67669;11845;12377.752444951231;12592.244465332109;12741.49034038628;12937.029722771018;13508.189508629514;14056.508022219174;14079.788803167756;14253.857461425254;14271.928640356731;12228.571999980313;12579.308138052129;12608.306543140216;12618.747625047265;12636.368181591068;10150.644727434505;10242.897571024492;10615.256932014685;10655.467226638792;10824.621138260123;12305.876941230474;12572.119951603665;12578.433970471167;12609.243445393306;12634.426041349854;12331.938340308241;12352.62942111738;12382.257064576153;12511.624651260478;12790.808137877753;11910.404479775803;12012.219205751599;12303.815442768544;12324.260544367367;12507.374631268582 17:35:13 INFO - PROCESS | 6422 | |3;mootools.html;1564.812222533296;1579.857812796848;1581.1619841484012;1587.111217718078;1588.4202266172847;11211.495482703278;11961.2823230606;11970.760584788299;11971.820422693656;11988.880111198885;11222.607208747695;11414.828777568344;11486.425678716052;11486.712832179199;11791.233569817978;2505.18581461025;2554.961675574868;2556.156468365444;2556.910508132216;2557.2328301509565;2305.14709557464;2320.153144102407;2324.8023917966993;2326.895289711967;2333.230034088755;2240.1039584166383;2241.0363543676212;2253.22263818983;2466.420391208074;2477.0587176872764;2204.900779464912;2243.977560224393;2331.94170145746;2339.415146213447;2341.051873991045;2431.732509423962;2433.3551608823677;2438.317271164059;2446.498467814094;2454.2882564056404;11631.59289424866;11948.701282468008;11961.880381196164;11973.521059157623;11976.52093916251;5911.793087241969;6033.245844269466;6049.455549000567;6052.334243233241;6068.544859135538;3572.8749493767855;3591.1560783215923;3626.9637303627155;3656.9634303657153;3657.9268414631824;1311.4557458654647;1389.4789453954768;1453.14264583896;1483.8441963593775;1523.116592376419;16929.57676058096;17321.393751218646;17457.21442535076;17473.475795726277;17514.91242543792;4709.458412282632;4768.451628062817;4780.400358530069;4818.469968303484;4865.6107511399005;13127.868721312856;13183.868161318456;13199.736005279841;13211.603651890458;13218.801717974238;5929.495992840657;5937.9406205937385;5937.960856850034;5948.494377977834;5957.970210148921;5392.433794451605;5450.482204190595;5564.19319198722;5639.3232812062815;5651.180578816049;4079.4296452947233;4129.669626429878;4135.1316223593385;4168.353905144647;4169.145325208325;1134.914881383883;1138.5502726423183;1141.6061458796703;1153.6250718516517;1158.6350299655583;1088.7441451258949;1104.4808939798281;1106.44124717019;1110.9888901111046;1114.5151858941224;7457.142428620669;7488.925110748932;7500.249975002565;7537.54774713519;7635.503692259986;1042.135027926819;1050.5482642463662;1052.752603138262;1060.2101434431397;1071.1484369925927;4670.929936050962;4857.635677324215;4914.164592019365;4918.631102667314;4953.603711703127;14056.367463464168;14847.109173449628;14871.47949821751;14880.479183228535;14897.329620167118;13308.467661293633;13781.310934453237;14737.705245895022;14738.926305368619;14753.483628072965;10760.46197690128;11008.39453830031;11046.944765276283;11056.778864422666;11059.336439813476;7414;7647.38820894316;7681.15507294186;7737.690492380467;7775.650095745592;12745.936270318589;12818.67953301175;12875.484980600671;12924.159929604546;13129.671758206121;13196.868031319947;13208.867911321147;13215.537456189371;13287.667808304483;13313.933430332787;10016.449095299828;10274.486275686037;10277.691669249934;10295.07344339013;10304.845427318745;11081.39052352096;11261.887381126411;11537.077033837106;11539.192256542297;11695.532178712756;9121.270298376247;9134.817303653757;9166.358354915359;9185.77035574116;9334.813303734023;9306.534673266446;9398.248140148908;9470.57382417779;9508.096730810563;9542.42745435276;7858;7874.606269686607;7919.08930473003;7944.324732397811;7992.080910695112;6079.240094988127;6114.425974336965;6172.969135154295;6234.251889773256;6312.116303717575;6474.805755827333;6479.319671434431;6494.090827284279;6589.571677840925;6668.499862510428 17:35:13 INFO - PROCESS | 6422 | |4;prototype.html;421.1639894808806;430.53072151355025;440.1680823244067;444.93548435476856;446.1768037969944;5179.274901513786;5241.633085684004;5252.60605454591;5264.552513036397;5266.736663166841;4981.227909673998;5128.769205385757;5187.507186817246;5190.480951904808;5191.688498690081;4333.826646934119;4383.641071267917;4387.407699960512;4413.587651951377;4421;4419.292913133899;4428.557144285578;4440.4893437254805;4448.4884238312525;4460.687751857371;4389.539098394671;4428.822847086113;4440.866773996785;4495.752123938031;4507.01691255848;4389.648828093761;4397.406350142722;4407.669424793137;4430.379746835446;4447.5552444755585;4157.708960372775;4166.7708276044805;4177.725793632941;4178.582141785828;4200.327947528412;5098.796048158069;5137.717425541594;5139.408967968657;5171.663841850305;5186.521841275218;4955.405351357824;5010.3737032870895;5024.598032157419;5049.596032317406;5112.156494178456;5398.919016214721;5440.755166017539;5478.753456094485;5486.396496385394;5510.338759348863;4480.417545719036;4517.9774101129615;4537.251353526664;4572.474165470947;4588.977055114737;5387.676739395649;5445.50990410861;5538.584606154514;5542.334919809568;5685.658860468385;4670.6497012724185;4679.64290355793;4697.224957881979;4709.717416954925;4751.216049351888;4813.066606728132;4839.685420447659;4848.660593758474;4853.417589889165;4868.513148685174;4599.218132917412;4769.73766442849;4773.665843390999;4968.826091086795;5126.820561280338;5350.678959262456;5400.864978375572;5428.37573679032;5449.9562664001;5481.260029895992;1614.5963509122719;1616.822149563547;1620.7892973913551;1621.9026858388534;1652.6942515634646;1224.797908345131;1254.4659787229928;1285.3894400160036;1288.7229245712028;1307.6600083978235;5246.366195010935;5384.326959130109;5446.7276636168035;5462.508374246337;5498.5876059294915;5523.171524271311;5526.640768350045;5549.500544950974;5555.666660000332;5558.77764889408;5357.1696387060065;5373.838784836461;5377.596680248997;5379.838604841861;5397.379301380313;2780.610714499931;2914.3879785245335;2923.7807164462324;2943.8528073596235;2956.733893949555;5795.275590551181;5804.709764511757;5805.593608447368;5808.344621782771;5823.941760582425;5631.6902570359025;5682.801101961411;5687.9431205687415;5704.885902282014;5713.457221563945;5556.055470570012;5812.622179558315;5856.355800861902;5869.941300587025;5907.493589134743;5623.409541998195;5638.661680299195;5643.915341269802;5656.830295091154;5659.971700141473;5366.838994830161;5540.695261760642;5549.38956714753;5562.666240025612;5611.943880561143;5836.299644042742;5838.708064596838;5861.267341582302;5867.383924687846;5869.8239052828485;4691.366665500251;4749.28760685886;4767.928481072718;4769.761511924459;4791.401074865642;5485.573750824894;5566.832995010156;5569.387367389665;5616.1856530803625;5649.858753531194;5418.485243901823;5435.184722291529;5449.711121329131;5470.124780035176;5477.4796394342475;5248.9737551312;5469.890602188014;5483.287172667528;5488.560915126701;5525.76206636313;5443.836684899459;5449.809256675997;5474.863128421821;5499.972500137474;5548.167774833807;5546.306711661043;5573.164025396223;5581.627808181588;5597.272354593877;5613.989481893298;5213.556847667991;5423.376311724202;5442.306058501067;5454.699991500347;5457.754401052041 17:35:13 INFO - PROCESS | 6422 | |5;yui.html;843.6203708331245;864.9264812490937;903.6927444668815;912.4662072687479;931.3015238571072;16750.74873876893;17853.19660615274;17864.732029019542;17942.641147177048;17982.91008544957;12465.439055242514;16547.089910054972;16600.25298861551;16705.498835034938;16715.582110447245;890;909.9545022748869;912.3522299167599;915.1946287267228;915.5879854065664;911.3301723233451;912.6623149434719;912.726182145357;913.0321858829628;913.0777914306532;955.5604421965869;956.0964888180672;956.1538038835611;956.5647630328184;957.3394357893067;950.34901092751;950.3870003847479;950.3870003847549;950.7385468996047;950.9239260859115;696.5716084607981;697.3305626598471;697.8604279144191;698.6192525073797;698.9615571143585;16294.185290735415;16374.918125409296;16386;16453.25960331776;16568;16439;16684.499465015946;16861.494155175365;17058;17624.383146589935;13549.187048777105;13826;13836.169829810244;13897;13900.860991390058;4014.698897582664;4031.1502515098305;4076.164386300801;4135.1729654069;4147.523034851;53814.46185538173;54269;66409;68065.65967170133;70335.94496082613;12035.819462708067;12358.62924112278;12650.746985060247;12661.240325580495;12747.42636581356;23706.88146559256;23834.16580419677;23973.7602623975;23990;23994.88002559976;8973.775655608531;8975.506347150838;9071.637134514678;9082.636694532277;9090.36546837163;5026.748662566857;5040.873978150502;5065.569426598707;5099.311592934981;5216.035033518811;3671.3391589514144;3740.495033170542;3745.850165993384;3748.5876553579083;3749.2876353492748;652.7160685101995;665.215046245435;667.359335038359;674.6222115615174;678.7997540725498;785.4462603864262;814.0841553252591;814.5866920892247;827.6896163938524;831.550962480266;11229.550817967352;11470.139739519569;11763.941180294043;11936.58221962227;12020.519179232733;762.1958833430783;778.9766307010799;790.2255789326377;792.8374683189933;802.9036515618163;5264.92102618461;5277.472252774692;5391.433899440581;5399.973000134974;5411.675299482044;76348.61825690836;78687.60656196797;81569;82342.58828705818;84186.57906710428;43009.56990430056;58949.70525147347;68846.311536885;68857.65571172112;69327.65336173287;62631;62651.37348626547;62844.05733913995;63047.054294185626;63365.68317158385;11264.380459074502;11550.191486596193;11580.594679186188;11763.411829408666;11930.463129159036;37637.81181094077;38501.80749096237;40714.59285407108;42450.78774606107;42494.36258456187;50374.2443863335;54272;55147;55886.72056639691;55957;14444;14528.85471145275;14567.635809104857;14654.63363415923;14736.189509577076;16420.917895410446;16756.74864877052;17001;17424.73862892083;17627;15072;15150.469733559272;15601.453949112181;15714.2928568217;16141.273642685872;14595.562133136023;15064.623384415478;15382.307796149424;15521.68956620884;15864.92067539655;15387.38450461969;15783.921080394524;16136.838631614004;16178.352865885703;16770.32918683239;10592.464092706668;11672.533098676298;12007.339596322287;12069;12282.87717122853;12938.417771200131;13109.672258193621;13159.144655597356;13225.140365876186;13249.583079519283 17:35:13 INFO - PROCESS | 6422 | __end_tp_report 17:35:13 INFO - PROCESS | 6422 | __start_cc_report 17:35:13 INFO - PROCESS | 6422 | _x_x_mozilla_cycle_collect,1353 17:35:13 INFO - PROCESS | 6422 | __end_cc_report 17:35:13 INFO - PROCESS | 6422 | __startTimestamp1491438913726__endTimestamp 17:35:13 INFO - PROCESS | 6422 | 17:35:13 INFO - PROCESS | 6422 | ------- Summary: start ------- 17:35:13 INFO - PROCESS | 6422 | Number of tests: 6 17:35:13 INFO - PROCESS | 6422 | 17:35:13 INFO - PROCESS | 6422 | [#0] dojo.html Cycles:165 Average:5810.46 Median:6068.58 stddev:676.39 (11.1%) stddev-sans-first:650.64 17:35:13 INFO - PROCESS | 6422 | Values: 3362.8 3365.8 3397.4 3409.5 3441.3 5764.1 5803.0 5821.8 5829.8 5847.8 5814.1 5865.9 5867.1 5875.1 5894.6 5207.9 5273.6 5311.4 5344.9 5345.2 5080.5 5105.7 5370.5 5386.7 5387.1 5249.0 5271.7 5315.3 5333.1 5356.2 5354.5 5357.1 5360.1 5368.5 5381.6 5096.2 5222.4 5270.0 5329.7 5339.8 5629.7 5694.8 5773.0 5785.5 5801.9 6265.2 6273.9 6274.6 6311.8 6321.4 6338.0 6360.0 6367.4 6371.4 6382.8 5633.7 5651.7 5793.8 5823.4 5840.5 6393.1 6405.7 6410.4 6410.6 6416.6 6144.5 6169.2 6215.8 6218.9 6232.4 6005.9 6006.1 6025.8 6156.3 6309.7 6216.2 6220.0 6233.8 6234.5 6252.3 5967.5 6000.8 6005.2 6124.6 6277.8 4773.9 4776.8 4794.3 4795.7 4797.8 4234.3 4244.5 4258.9 4272.1 4284.6 5715.5 5802.1 5925.0 5987.9 6068.6 6306.8 6321.7 6324.9 6330.8 6358.8 6090.0 6099.9 6105.8 6110.6 6112.5 5121.8 5139.0 5285.2 5324.2 5389.8 6579.9 6592.5 6597.7 6598.5 6604.8 6251.8 6261.0 6270.1 6313.8 6328.9 6193.1 6256.3 6462.3 6610.7 6626.3 6227.2 6245.8 6259.6 6261.1 6270.5 6354.8 6359.2 6361.1 6364.8 6368.9 5886.5 5905.5 6134.8 6172.8 6191.3 6092.5 6113.0 6154.8 6204.3 6205.8 6155.0 6184.1 6185.0 6185.6 6189.0 5839.8 5870.0 5998.7 6175.7 6186.1 5981.6 6053.8 6078.8 6139.1 6140.4 17:35:13 INFO - PROCESS | 6422 | 17:35:13 INFO - PROCESS | 6422 | [#1] ext.html Cycles:180 Average:44987.85 Median:30708.73 stddev:75686.04 (246.5%) stddev-sans-first:75825.51 17:35:13 INFO - PROCESS | 6422 | Values: 761.2 828.5 846.9 920.9 934.4 30891.0 42733.4 43338.6 47049.0 47246.3 44501.6 44787.8 44812.8 44875.3 44900.8 7811.3 7974.4 8961.2 9066.7 9076.8 10521.7 10728.0 10729.6 10730.8 10733.1 10900.3 10922.8 10937.1 10937.1 10946.5 10848.5 10850.1 10867.9 10876.7 10891.0 10609.7 10652.8 10663.3 10663.3 10667.6 41211.8 41436.6 41476.0 41568.6 41893.8 4267.5 4307.5 4353.0 4434.7 4443.2 29432.9 29642.9 29856.9 30350.1 31231.0 4326.9 4451.7 4458.5 4495.6 4495.9 240567.0 241854.0 242154.0 242555.0 242567.0 53162.0 53217.5 53225.5 53228.5 53321.7 54903.2 54955.5 54969.7 55115.4 55278.7 16426.3 16787.1 16787.5 16831.0 16832.3 13099.8 13329.7 14562.7 15029.3 15060.1 12778.0 12893.1 12922.4 12938.6 13043.5 5263.9 5270.0 5271.5 5387.3 5436.7 1001.9 1009.7 1016.3 1020.5 1021.6 28003.3 28195.4 28541.6 29284.9 30250.7 1175.6 1179.2 1181.3 1187.3 1193.7 14608.0 14628.9 14655.4 14796.4 14826.9 329866.0 331278.0 331893.0 331955.0 332555.0 46806.1 46818.5 46850.5 46865.0 46977.5 1477.7 1486.7 1509.4 1524.5 1549.8 24890.4 26049.5 26289.0 26416.0 26427.0 36024.6 36100.3 36158.6 36173.6 36220.3 285802.0 288489.0 289166.0 289756.0 290380.0 25761.1 25970.0 28109.9 30291.4 31238.2 39265.4 40358.4 43272.1 43315.6 43720.8 30855.2 31038.7 31325.8 31518.0 31661.2 31007.4 31167.7 31336.0 31345.5 31713.2 34775.8 35232.1 35308.5 35580.0 35810.5 30039.2 30173.5 30562.2 30913.4 31145.8 24087.8 24817.0 24957.3 25093.7 25353.6 17:35:13 INFO - PROCESS | 6422 | 17:35:13 INFO - PROCESS | 6422 | [#2] jquery.html Cycles:180 Average:37194.17 Median:2321.49 stddev:109342.05 (4710.0%) stddev-sans-first:109613.92 17:35:13 INFO - PROCESS | 6422 | Values: 421.9 441.8 451.3 470.3 490.1 19386.4 19929.6 20421.8 20548.0 21396.9 20184.5 21745.7 22084.8 22258.8 22448.7 6049.9 6371.2 6620.6 6675.6 6678.9 7680.8 7724.3 7774.0 7784.7 7785.8 7290.3 7566.4 7575.7 7588.3 7809.8 7540.1 7704.0 7706.9 7753.6 7799.1 7338.9 7565.8 7574.6 7577.1 7587.8 24382.3 24749.1 24767.9 24970.5 25357.4 3129.3 3135.4 3163.3 3165.8 3571.2 16220.5 16327.3 16569.4 16579.3 16579.7 2238.4 2253.8 2296.2 2315.9 2327.1 114985.4 115565.0 115781.0 117727.4 118754.4 17621.0 17932.9 18104.9 18316.6 18561.8 32333.5 32390.4 32493.7 32552.0 32557.8 10152.3 10250.0 10261.8 10342.6 10388.7 8622.6 8648.2 8711.3 8761.5 8833.9 9127.8 9132.8 9246.4 9410.3 9418.7 3479.8 3728.6 3738.6 3783.9 3823.5 926.4 933.4 935.1 940.9 945.0 19572.3 20589.5 21373.9 21493.4 21581.0 933.9 960.9 961.7 973.3 974.8 10987.9 11274.2 11411.5 11472.7 11580.1 651254.0 662505.0 667032.0 672243.7 674581.0 67074.0 73010.3 78516.6 112218.4 117096.0 1218.4 1231.1 1243.4 1254.2 1262.5 16784.6 18090.5 18557.0 18907.6 19088.8 51063.0 51260.7 51286.2 51983.2 52728.7 64907.4 65798.0 65878.0 66989.0 67669.0 11845.0 12377.8 12592.2 12741.5 12937.0 13508.2 14056.5 14079.8 14253.9 14271.9 12228.6 12579.3 12608.3 12618.7 12636.4 10150.6 10242.9 10615.3 10655.5 10824.6 12305.9 12572.1 12578.4 12609.2 12634.4 12331.9 12352.6 12382.3 12511.6 12790.8 11910.4 12012.2 12303.8 12324.3 12507.4 17:35:13 INFO - PROCESS | 6422 | 17:35:13 INFO - PROCESS | 6422 | [#3] mootools.html Cycles:180 Average:7252.99 Median:2312.65 stddev:4609.36 (199.3%) stddev-sans-first:4602.47 17:35:13 INFO - PROCESS | 6422 | Values: 1564.8 1579.9 1581.2 1587.1 1588.4 11211.5 11961.3 11970.8 11971.8 11988.9 11222.6 11414.8 11486.4 11486.7 11791.2 2505.2 2555.0 2556.2 2556.9 2557.2 2305.1 2320.2 2324.8 2326.9 2333.2 2240.1 2241.0 2253.2 2466.4 2477.1 2204.9 2244.0 2331.9 2339.4 2341.1 2431.7 2433.4 2438.3 2446.5 2454.3 11631.6 11948.7 11961.9 11973.5 11976.5 5911.8 6033.2 6049.5 6052.3 6068.5 3572.9 3591.2 3627.0 3657.0 3657.9 1311.5 1389.5 1453.1 1483.8 1523.1 16929.6 17321.4 17457.2 17473.5 17514.9 4709.5 4768.5 4780.4 4818.5 4865.6 13127.9 13183.9 13199.7 13211.6 13218.8 5929.5 5937.9 5938.0 5948.5 5958.0 5392.4 5450.5 5564.2 5639.3 5651.2 4079.4 4129.7 4135.1 4168.4 4169.1 1134.9 1138.6 1141.6 1153.6 1158.6 1088.7 1104.5 1106.4 1111.0 1114.5 7457.1 7488.9 7500.2 7537.5 7635.5 1042.1 1050.5 1052.8 1060.2 1071.1 4670.9 4857.6 4914.2 4918.6 4953.6 14056.4 14847.1 14871.5 14880.5 14897.3 13308.5 13781.3 14737.7 14738.9 14753.5 10760.5 11008.4 11046.9 11056.8 11059.3 7414.0 7647.4 7681.2 7737.7 7775.7 12745.9 12818.7 12875.5 12924.2 13129.7 13196.9 13208.9 13215.5 13287.7 13313.9 10016.4 10274.5 10277.7 10295.1 10304.8 11081.4 11261.9 11537.1 11539.2 11695.5 9121.3 9134.8 9166.4 9185.8 9334.8 9306.5 9398.2 9470.6 9508.1 9542.4 7858.0 7874.6 7919.1 7944.3 7992.1 6079.2 6114.4 6173.0 6234.3 6312.1 6474.8 6479.3 6494.1 6589.6 6668.5 17:35:13 INFO - PROCESS | 6422 | 17:35:13 INFO - PROCESS | 6422 | [#4] prototype.html Cycles:180 Average:4787.11 Median:5258.58 stddev:1266.58 (24.1%) stddev-sans-first:1227.01 17:35:13 INFO - PROCESS | 6422 | Values: 421.2 430.5 440.2 444.9 446.2 5179.3 5241.6 5252.6 5264.6 5266.7 4981.2 5128.8 5187.5 5190.5 5191.7 4333.8 4383.6 4387.4 4413.6 4421.0 4419.3 4428.6 4440.5 4448.5 4460.7 4389.5 4428.8 4440.9 4495.8 4507.0 4389.6 4397.4 4407.7 4430.4 4447.6 4157.7 4166.8 4177.7 4178.6 4200.3 5098.8 5137.7 5139.4 5171.7 5186.5 4955.4 5010.4 5024.6 5049.6 5112.2 5398.9 5440.8 5478.8 5486.4 5510.3 4480.4 4518.0 4537.3 4572.5 4589.0 5387.7 5445.5 5538.6 5542.3 5685.7 4670.6 4679.6 4697.2 4709.7 4751.2 4813.1 4839.7 4848.7 4853.4 4868.5 4599.2 4769.7 4773.7 4968.8 5126.8 5350.7 5400.9 5428.4 5450.0 5481.3 1614.6 1616.8 1620.8 1621.9 1652.7 1224.8 1254.5 1285.4 1288.7 1307.7 5246.4 5384.3 5446.7 5462.5 5498.6 5523.2 5526.6 5549.5 5555.7 5558.8 5357.2 5373.8 5377.6 5379.8 5397.4 2780.6 2914.4 2923.8 2943.9 2956.7 5795.3 5804.7 5805.6 5808.3 5823.9 5631.7 5682.8 5687.9 5704.9 5713.5 5556.1 5812.6 5856.4 5869.9 5907.5 5623.4 5638.7 5643.9 5656.8 5660.0 5366.8 5540.7 5549.4 5562.7 5611.9 5836.3 5838.7 5861.3 5867.4 5869.8 4691.4 4749.3 4767.9 4769.8 4791.4 5485.6 5566.8 5569.4 5616.2 5649.9 5418.5 5435.2 5449.7 5470.1 5477.5 5249.0 5469.9 5483.3 5488.6 5525.8 5443.8 5449.8 5474.9 5500.0 5548.2 5546.3 5573.2 5581.6 5597.3 5614.0 5213.6 5423.4 5442.3 5454.7 5457.8 17:35:13 INFO - PROCESS | 6422 | 17:35:13 INFO - PROCESS | 6422 | [#5] yui.html Cycles:180 Average:17810.25 Median:4105.67 stddev:20844.98 (507.7%) stddev-sans-first:20864.51 17:35:13 INFO - PROCESS | 6422 | Values: 843.6 864.9 903.7 912.5 931.3 16750.7 17853.2 17864.7 17942.6 17982.9 12465.4 16547.1 16600.3 16705.5 16715.6 890.0 910.0 912.4 915.2 915.6 911.3 912.7 912.7 913.0 913.1 955.6 956.1 956.2 956.6 957.3 950.3 950.4 950.4 950.7 950.9 696.6 697.3 697.9 698.6 699.0 16294.2 16374.9 16386.0 16453.3 16568.0 16439.0 16684.5 16861.5 17058.0 17624.4 13549.2 13826.0 13836.2 13897.0 13900.9 4014.7 4031.2 4076.2 4135.2 4147.5 53814.5 54269.0 66409.0 68065.7 70335.9 12035.8 12358.6 12650.7 12661.2 12747.4 23706.9 23834.2 23973.8 23990.0 23994.9 8973.8 8975.5 9071.6 9082.6 9090.4 5026.7 5040.9 5065.6 5099.3 5216.0 3671.3 3740.5 3745.9 3748.6 3749.3 652.7 665.2 667.4 674.6 678.8 785.4 814.1 814.6 827.7 831.6 11229.6 11470.1 11763.9 11936.6 12020.5 762.2 779.0 790.2 792.8 802.9 5264.9 5277.5 5391.4 5400.0 5411.7 76348.6 78687.6 81569.0 82342.6 84186.6 43009.6 58949.7 68846.3 68857.7 69327.7 62631.0 62651.4 62844.1 63047.1 63365.7 11264.4 11550.2 11580.6 11763.4 11930.5 37637.8 38501.8 40714.6 42450.8 42494.4 50374.2 54272.0 55147.0 55886.7 55957.0 14444.0 14528.9 14567.6 14654.6 14736.2 16420.9 16756.7 17001.0 17424.7 17627.0 15072.0 15150.5 15601.5 15714.3 16141.3 14595.6 15064.6 15382.3 15521.7 15864.9 15387.4 15783.9 16136.8 16178.4 16770.3 10592.5 11672.5 12007.3 12069.0 12282.9 12938.4 13109.7 13159.1 13225.1 13249.6 17:35:13 INFO - PROCESS | 6422 | -------- Summary: end -------- 17:35:13 INFO - PROCESS | 6422 | 17:35:14 INFO - TEST-INFO | 6422: exit 0 17:35:14 INFO - TEST-OK | dromaeo_css | took 1114679ms 17:35:14 INFO - TEST-START | kraken 17:35:14 INFO - operating with platform_type : linux_ 17:35:14 INFO - Initialising browser for kraken test... 17:35:14 INFO - TEST-INFO | started process 6759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0avk_Z/profile http://localhost:52834/getInfo.html) 17:35:18 INFO - PROCESS | 6759 | __metrics Screen width/height:1600/1200 17:35:18 INFO - PROCESS | 6759 | colorDepth:24 17:35:18 INFO - PROCESS | 6759 | Browser inner width/height: 1024/697 17:35:18 INFO - PROCESS | 6759 | __metrics 17:35:18 INFO - PROCESS | 6759 | 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" 17:35:18 INFO - PROCESS | 6759 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:35:19 INFO - TEST-INFO | 6759: exit 0 17:35:19 INFO - Browser initialized. 17:35:19 INFO - Running cycle 1/1 for kraken test... 17:35:19 INFO - TEST-INFO | started process 6850 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0avk_Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:31 INFO - PROCESS | 6850 | RSS: Main: 140058624 17:35:31 INFO - PROCESS | 6850 | 17:35:34 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52834/tests/kraken/driver.html?testName=audio-beat-detection) 17:35:34 INFO - PROCESS | 6850 | RSS: Main: 184152064 17:35:34 INFO - PROCESS | 6850 | 17:35:37 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52834/tests/kraken/driver.html?testName=audio-dft) 17:35:37 INFO - PROCESS | 6850 | RSS: Main: 173240320 17:35:37 INFO - PROCESS | 6850 | 17:35:40 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52834/tests/kraken/driver.html?testName=audio-fft) 17:35:41 INFO - PROCESS | 6850 | RSS: Main: 265072640 17:35:41 INFO - PROCESS | 6850 | 17:35:43 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52834/tests/kraken/driver.html?testName=audio-oscillator) 17:35:44 INFO - PROCESS | 6850 | RSS: Main: 171511808 17:35:44 INFO - PROCESS | 6850 | 17:35:46 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52834/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:35:46 INFO - PROCESS | 6850 | RSS: Main: 174018560 17:35:46 INFO - PROCESS | 6850 | 17:35:49 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52834/tests/kraken/driver.html?testName=imaging-darkroom) 17:35:50 INFO - PROCESS | 6850 | RSS: Main: 209227776 17:35:50 INFO - PROCESS | 6850 | 17:35:53 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52834/tests/kraken/driver.html?testName=imaging-desaturate) 17:35:54 INFO - PROCESS | 6850 | RSS: Main: 211984384 17:35:54 INFO - PROCESS | 6850 | 17:35:56 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52834/tests/kraken/driver.html?testName=json-parse-financial) 17:35:57 INFO - PROCESS | 6850 | RSS: Main: 210821120 17:35:57 INFO - PROCESS | 6850 | 17:35:59 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52834/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:35:59 INFO - PROCESS | 6850 | RSS: Main: 173092864 17:35:59 INFO - PROCESS | 6850 | 17:36:01 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52834/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:36:01 INFO - PROCESS | 6850 | RSS: Main: 179367936 17:36:01 INFO - PROCESS | 6850 | 17:36:03 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52834/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:36:04 INFO - PROCESS | 6850 | RSS: Main: 189231104 17:36:04 INFO - PROCESS | 6850 | 17:36:06 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52834/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:36:07 INFO - PROCESS | 6850 | RSS: Main: 198725632 17:36:07 INFO - PROCESS | 6850 | 17:36:10 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52834/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:36:11 INFO - PROCESS | 6850 | RSS: Main: 194924544 17:36:11 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | Cycle 1(1): loaded http://localhost:52834/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52834/tests/kraken/driver.html?testName=ai-astar) 17:36:12 INFO - PROCESS | 6850 | RSS: Main: 244842496 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | __start_tp_report 17:36:12 INFO - PROCESS | 6850 | _x_x_mozilla_page_load 17:36:12 INFO - PROCESS | 6850 | _x_x_mozilla_page_load_details 17:36:12 INFO - PROCESS | 6850 | |i|pagename|runs| 17:36:12 INFO - PROCESS | 6850 | |0;ai-astar;105;110;112;112;112;111;110;111;112;112 17:36:12 INFO - PROCESS | 6850 | |1;audio-beat-detection;138;124;116;145;116;115;115;119;134;115 17:36:12 INFO - PROCESS | 6850 | |2;audio-dft;162;161;163;161;161;160;162;160;161;161 17:36:12 INFO - PROCESS | 6850 | |3;audio-fft;74;75;76;114;76;76;75;76;90;75 17:36:12 INFO - PROCESS | 6850 | |4;audio-oscillator;81;81;106;82;83;82;93;82;82;112 17:36:12 INFO - PROCESS | 6850 | |5;imaging-gaussian-blur;113;113;113;113;113;113;113;114;112;112 17:36:12 INFO - PROCESS | 6850 | |6;imaging-darkroom;146;145;146;146;146;146;146;146;146;145 17:36:12 INFO - PROCESS | 6850 | |7;imaging-desaturate;94;94;94;94;94;93;94;94;93;94 17:36:12 INFO - PROCESS | 6850 | |8;json-parse-financial;56;53;57;55;53;53;55;55;54;54 17:36:12 INFO - PROCESS | 6850 | |9;json-stringify-tinderbox;53;52;54;53;52;52;53;53;53;53 17:36:12 INFO - PROCESS | 6850 | |10;stanford-crypto-aes;78;80;79;76;83;76;75;75;83;75 17:36:12 INFO - PROCESS | 6850 | |11;stanford-crypto-ccm;132;132;123;121;128;135;119;118;160;117 17:36:12 INFO - PROCESS | 6850 | |12;stanford-crypto-pbkdf2;167;159;160;165;158;159;166;159;160;156 17:36:12 INFO - PROCESS | 6850 | |13;stanford-crypto-sha256-iterative;60;60;60;59;59;64;59;59;58;57 17:36:12 INFO - PROCESS | 6850 | __end_tp_report 17:36:12 INFO - PROCESS | 6850 | __start_cc_report 17:36:12 INFO - PROCESS | 6850 | _x_x_mozilla_cycle_collect,1646 17:36:12 INFO - PROCESS | 6850 | __end_cc_report 17:36:12 INFO - PROCESS | 6850 | __startTimestamp1491438972890__endTimestamp 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | ------- Summary: start ------- 17:36:12 INFO - PROCESS | 6850 | Number of tests: 14 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:2.16 (1.9%) stddev-sans-first:0.87 17:36:12 INFO - PROCESS | 6850 | Values: 105.0 110.0 112.0 112.0 112.0 111.0 110.0 111.0 112.0 112.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:121.50 stddev:11.22 (9.2%) stddev-sans-first:10.64 17:36:12 INFO - PROCESS | 6850 | Values: 138.0 124.0 116.0 145.0 116.0 115.0 115.0 119.0 134.0 115.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 17:36:12 INFO - PROCESS | 6850 | Values: 162.0 161.0 163.0 161.0 161.0 160.0 162.0 160.0 161.0 161.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#3] audio-fft Cycles:10 Average:80.70 Median:76.00 stddev:12.59 (16.6%) stddev-sans-first:13.12 17:36:12 INFO - PROCESS | 6850 | Values: 74.0 75.0 76.0 114.0 76.0 76.0 75.0 76.0 90.0 75.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#4] audio-oscillator Cycles:10 Average:88.40 Median:82.00 stddev:11.50 (14.0%) stddev-sans-first:11.88 17:36:12 INFO - PROCESS | 6850 | Values: 81.0 81.0 106.0 82.0 83.0 82.0 93.0 82.0 82.0 112.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#5] imaging-gaussian-blur Cycles:10 Average:112.90 Median:113.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 17:36:12 INFO - PROCESS | 6850 | Values: 113.0 113.0 113.0 113.0 113.0 113.0 113.0 114.0 112.0 112.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 17:36:12 INFO - PROCESS | 6850 | Values: 146.0 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 17:36:12 INFO - PROCESS | 6850 | Values: 94.0 94.0 94.0 94.0 94.0 93.0 94.0 94.0 93.0 94.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.35 (2.5%) stddev-sans-first:1.32 17:36:12 INFO - PROCESS | 6850 | Values: 56.0 53.0 57.0 55.0 53.0 53.0 55.0 55.0 54.0 54.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#9] json-stringify-tinderbox Cycles:10 Average:52.80 Median:53.00 stddev:0.63 (1.2%) stddev-sans-first:0.67 17:36:12 INFO - PROCESS | 6850 | Values: 53.0 52.0 54.0 53.0 52.0 52.0 53.0 53.0 53.0 53.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.50 stddev:3.16 (4.0%) stddev-sans-first:3.35 17:36:12 INFO - PROCESS | 6850 | Values: 78.0 80.0 79.0 76.0 83.0 76.0 75.0 75.0 83.0 75.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#11] stanford-crypto-ccm Cycles:10 Average:128.50 Median:130.00 stddev:12.82 (9.9%) stddev-sans-first:13.53 17:36:12 INFO - PROCESS | 6850 | Values: 132.0 132.0 123.0 121.0 128.0 135.0 119.0 118.0 160.0 117.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.90 Median:160.00 stddev:3.73 (2.3%) stddev-sans-first:3.23 17:36:12 INFO - PROCESS | 6850 | Values: 167.0 159.0 160.0 165.0 158.0 159.0 166.0 159.0 160.0 156.0 17:36:12 INFO - PROCESS | 6850 | 17:36:12 INFO - PROCESS | 6850 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.50 Median:59.50 stddev:1.84 (3.1%) stddev-sans-first:1.94 17:36:12 INFO - PROCESS | 6850 | Values: 60.0 60.0 60.0 59.0 59.0 64.0 59.0 59.0 58.0 57.0 17:36:12 INFO - PROCESS | 6850 | -------- Summary: end -------- 17:36:12 INFO - PROCESS | 6850 | 17:36:13 INFO - TEST-INFO | 6850: exit 0 17:36:14 INFO - TEST-OK | kraken | took 59465ms 17:36:14 INFO - SUITE-END | took 1174s 17:36:14 INFO - Completed test suite (00:19:34) 17:36:14 INFO - javascript benchmark 17:36:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7860.401143890362, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.798232106074, 3365.7980521168715, 3397.4394224952875, 3409.5397121388605, 3441.2945346204015, 5764.135379693048, 5803.042497987837, 5821.825345239639, 5829.825105246844, 5847.824565263044, 5814.1278808178795, 5865.853353666161, 5867.149263356821, 5875.118732190173, 5894.64632122073, 5207.869803254911, 5273.630845840793, 5311.442298558634, 5344.893102137955, 5345.224942383352, 5080.542751152396, 5105.719185444799, 5370.489803468664, 5386.703731294777, 5387.1111266640955, 5249.028929648009, 5271.683698978054, 5315.255864179018, 5333.066713325172, 5356.196570514435, 5354.464553544653, 5357.142857142878, 5360.115580929142, 5368.463153684639, 5381.596380271456, 5096.210087436453, 5222.4255331913455, 5269.973650131763, 5329.653572517774, 5339.813106541253, 5629.7185140742795, 5694.77220911163, 5773.047447171211, 5785.537157027427, 5801.941980580183, 6265.154204182422, 6273.87452250957, 6274.56078074535, 6311.842203944892, 6321.399467050623, 6338.0, 6360.0459931010255, 6367.395097465734, 6371.39471750183, 6382.840428989312, 5633.690147041911, 5651.660900345952, 5793.797217097382, 5823.417658234227, 5840.532757379485, 6393.136926514954, 6405.679716014274, 6410.391012853772, 6410.551261411656, 6416.615003099736, 6144.5084393248435, 6169.198004259507, 6215.844603884939, 6218.906716399258, 6232.407921247474, 6005.879882402328, 6006.12911127884, 6025.849353766191, 6156.26124865019, 6309.652969086745, 6216.160818289563, 6220.03589443637, 6233.812985610439, 6234.470070044006, 6252.280987686383, 5967.46292833647, 6000.789972350947, 6005.249343832021, 6124.601900876429, 6277.780277690259, 4773.928391074137, 4776.761161941889, 4794.328793968847, 4795.712257264575, 4797.832075877328, 4234.343676730112, 4244.469441319835, 4258.936115958263, 4272.124214536013, 4284.592963668447, 5715.514181294553, 5802.071668533014, 5925.0, 5987.9101813472835, 6068.575199736064, 6306.842328941813, 6321.715522801485, 6324.873502529924, 6330.8100756977365, 6358.77744278948, 6089.969550152221, 6099.9390006099375, 6105.8168254952425, 6110.633361998294, 6112.480439162721, 5121.795128194831, 5139.0, 5285.180796976475, 5324.227986941872, 5389.757460914268, 6579.934200658028, 6592.538522303393, 6597.670116494252, 6598.505112116706, 6604.8348791280605, 6251.78118765841, 6261.0, 6270.059491076375, 6313.810585682437, 6328.873422531431, 6193.1329613853195, 6256.311805701278, 6462.289148193601, 6610.70251838678, 6626.27111017778, 6227.19046523949, 6245.750169993149, 6259.593126446766, 6261.092141639346, 6270.49836013127, 6354.809355719336, 6359.17330747, 6361.14124593174, 6364.77723279683, 6368.9044664330995, 5886.499647529837, 5905.4685078343155, 6134.754609815557, 6172.814815555541, 6191.318954915046, 6092.543059270483, 6112.969435152796, 6154.784582539769, 6204.348543402878, 6205.782797602063, 6154.984427569401, 6184.072389141665, 6184.969075154595, 6185.628862268279, 6189.0, 5839.795607153729, 5870.0, 5998.7300571473515, 6175.69121543912, 6186.133941248138, 5981.551383646157, 6053.75784968614, 6078.756849726138, 6139.079138129317, 6140.355262697352], "value": 5764.316310401706, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.2197497564995, 828.4532208742231, 846.9364797640177, 920.9033051529585, 934.3506263147116, 30891.0, 42733.35899961503, 43338.56661433389, 47049.0, 47246.291305630446, 44501.554984450144, 44787.77606111965, 44812.77593612028, 44875.32687009697, 44900.77549612256, 7811.336036436881, 7974.441789074767, 8961.193492585666, 9066.727998160066, 9076.773080673003, 10521.68434946949, 10728.0, 10729.624463143791, 10730.839037414446, 10733.08768754653, 10900.291481053746, 10922.781544369107, 10937.125029997582, 10937.125029997582, 10946.507407166697, 10848.457577121151, 10850.077743391781, 10867.891321086767, 10876.728081797939, 10891.0, 10609.681709548726, 10652.840207396817, 10663.253572249945, 10663.306885052447, 10667.626633067805, 41211.7939410304, 41436.58563414388, 41476.0, 41568.584314156775, 41893.79053104745, 4267.509236437819, 4307.5261721210645, 4353.0423306872435, 4434.711743736679, 4443.2224360736745, 29432.8528357359, 29642.85178574115, 29856.8507157465, 30350.089497315115, 31231.0, 4326.935095973563, 4451.666125040635, 4458.487273963503, 4495.57292057254, 4495.865124046284, 240567.0, 241854.0, 242154.0, 242555.0, 242567.0, 53162.0, 53217.46782532125, 53225.46774532283, 53228.467715323124, 53321.7333913328, 54903.17645235325, 54955.45044549504, 54969.72515137479, 55115.44884551183, 55278.72360638252, 16426.260818263207, 16787.076710781024, 16787.496375108767, 16831.0, 16832.326706931584, 13099.803502947463, 13329.66675833112, 14562.708745825024, 15029.323680434407, 15060.096394216382, 12778.0, 12893.097483176276, 12922.353882306035, 12938.611841644766, 13043.543475978295, 5263.894722105613, 5269.973650131801, 5271.499207575274, 5387.272718182995, 5436.70098144598, 1001.8897921228659, 1009.7374682582581, 1016.2733645443543, 1020.5407566595062, 1021.5556233038652, 28003.29991750182, 28195.43609127806, 28541.57187642187, 29284.853575731984, 30250.69749302523, 1175.5709166154293, 1179.204037274836, 1181.3030312115893, 1187.2994932989404, 1193.7194759231575, 14608.0, 14628.926855365655, 14655.413783448543, 14796.408143674133, 14826.9046478197, 329866.0, 331278.0, 331893.0, 331955.0, 332555.0, 46806.063878722234, 46818.53181468142, 46850.53149468462, 46865.0, 46977.530224698, 1477.7340078785708, 1486.6655002624539, 1509.4415066425493, 1524.5045360257961, 1549.7907782449452, 24890.377740556632, 26049.479010419305, 26289.0, 26416.0, 26427.0, 36024.639753602125, 36100.27799443944, 36158.638413615525, 36173.638263617024, 36220.27559448744, 285802.0, 288489.0, 289166.0, 289756.0, 290380.0, 25761.098361557255, 25970.0, 28109.859450703432, 30291.394172115994, 31238.21904452316, 39265.411018835315, 40358.39462408007, 43272.13455730805, 43315.56684433115, 43720.78139609282, 30855.2286192847, 31038.68961310358, 31325.843370783, 31518.0, 31661.20846978844, 31007.379852403275, 31167.68832311648, 31336.0, 31345.52981705322, 31713.20716982094, 34775.826120870246, 35232.11919702028, 35308.47037294391, 35580.0, 35810.462843056855, 30039.249018774706, 30173.54739678863, 30562.235944101576, 30913.381732365677, 31145.8442707785, 24087.75912240855, 24817.0, 24957.251282461555, 25093.74906250914, 25353.619695704947], "value": 18633.431906107842, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.91139860629266, 441.7614488176385, 451.3455489540166, 470.3368250766419, 490.1128956588575, 19386.41840744778, 19929.60140797185, 20421.795782042176, 20548.0, 21396.89301553492, 20184.495387615316, 21745.67381489279, 22084.779152208474, 22258.777412225914, 22448.66327005096, 6049.939500605004, 6371.171747672807, 6620.602763834161, 6675.566088204276, 6678.933210667904, 7680.807979800522, 7724.304812566867, 7773.961130194341, 7784.688612455495, 7785.766427007171, 7290.27097290272, 7566.394688424913, 7575.696972121109, 7588.27911348421, 7809.804754881116, 7540.095188577406, 7704.0, 7706.884396734054, 7753.6123193840085, 7799.142094369615, 7338.926610733878, 7565.773026809149, 7574.621268936587, 7577.090749110087, 7587.81030474237, 24382.268531943893, 24749.13378031778, 24767.87616061926, 24970.5005899881, 25357.366065848317, 3129.264622813637, 3135.357251763383, 3163.2724473371254, 3165.7942233754734, 3571.178628915338, 16220.51338459848, 16327.265273062741, 16569.4200702976, 16579.336826526924, 16579.6684066318, 2238.3844442778204, 2253.752087270399, 2296.2307626944994, 2315.8731736407944, 2327.1156960354956, 114985.4250728741, 115565.0, 115781.0, 117727.41136294349, 118754.40622796917, 17621.0, 17932.91033544824, 18104.913705177732, 18316.633667326845, 18561.81438185628, 32333.51499727506, 32390.35219295601, 32493.675063249066, 32552.0, 32557.837210813796, 10152.289339746147, 10249.97500249984, 10261.846072308921, 10342.586296548054, 10388.740281492872, 8622.611982460805, 8648.1784230498, 8711.303095752324, 8761.518116503526, 8833.867491987625, 9127.771805704777, 9132.81734365309, 9246.398984066149, 9410.341276110606, 9418.670346537838, 3479.7912125272564, 3728.6374941318722, 3738.5887552369218, 3783.886483405502, 3823.4545790159527, 926.420986883198, 933.4306073295285, 935.0696057422907, 940.9153176214173, 944.9574769135405, 19572.314968976018, 20589.485262868548, 21373.893130534558, 21493.355199344045, 21581.0, 933.85992101184, 960.9183219426287, 961.6730311693917, 973.3284034016506, 974.7563109222694, 10987.945060274807, 11274.210805243554, 11411.48648310828, 11472.713182170346, 11580.131490138274, 651254.0, 662505.0, 667032.0, 672243.7490312947, 674581.0, 67074.0, 73010.2698973014, 78516.60741696255, 112218.43890780494, 117096.0, 1218.4029825385496, 1231.1259006105765, 1243.421808858885, 1254.203580726227, 1262.450833887262, 16784.58038549046, 18090.522560692367, 18557.0, 18907.62184756325, 19088.809111909257, 51063.0, 51260.74369628128, 51286.230706538685, 51983.220251697014, 52728.736356317975, 64907.35092649013, 65798.0, 65878.0, 66989.0, 67669.0, 11845.0, 12377.752444951231, 12592.244465332109, 12741.49034038628, 12937.029722771018, 13508.189508629514, 14056.508022219174, 14079.788803167756, 14253.857461425254, 14271.928640356731, 12228.571999980313, 12579.308138052129, 12608.306543140216, 12618.747625047265, 12636.368181591068, 10150.644727434505, 10242.897571024492, 10615.256932014685, 10655.467226638792, 10824.621138260123, 12305.876941230474, 12572.119951603665, 12578.433970471167, 12609.243445393306, 12634.426041349854, 12331.938340308241, 12352.62942111738, 12382.257064576153, 12511.624651260478, 12790.808137877753, 11910.404479775803, 12012.219205751599, 12303.815442768544, 12324.260544367367, 12507.374631268582], "value": 11432.730758902177, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1564.812222533296, 1579.857812796848, 1581.1619841484012, 1587.111217718078, 1588.4202266172847, 11211.495482703278, 11961.2823230606, 11970.760584788299, 11971.820422693656, 11988.880111198885, 11222.607208747695, 11414.828777568344, 11486.425678716052, 11486.712832179199, 11791.233569817978, 2505.18581461025, 2554.961675574868, 2556.156468365444, 2556.910508132216, 2557.2328301509565, 2305.14709557464, 2320.153144102407, 2324.8023917966993, 2326.895289711967, 2333.230034088755, 2240.1039584166383, 2241.0363543676212, 2253.22263818983, 2466.420391208074, 2477.0587176872764, 2204.900779464912, 2243.977560224393, 2331.94170145746, 2339.415146213447, 2341.051873991045, 2431.732509423962, 2433.3551608823677, 2438.317271164059, 2446.498467814094, 2454.2882564056404, 11631.59289424866, 11948.701282468008, 11961.880381196164, 11973.521059157623, 11976.52093916251, 5911.793087241969, 6033.245844269466, 6049.455549000567, 6052.334243233241, 6068.544859135538, 3572.8749493767855, 3591.1560783215923, 3626.9637303627155, 3656.9634303657153, 3657.9268414631824, 1311.4557458654647, 1389.4789453954768, 1453.14264583896, 1483.8441963593775, 1523.116592376419, 16929.57676058096, 17321.393751218646, 17457.21442535076, 17473.475795726277, 17514.91242543792, 4709.458412282632, 4768.451628062817, 4780.400358530069, 4818.469968303484, 4865.6107511399005, 13127.868721312856, 13183.868161318456, 13199.736005279841, 13211.603651890458, 13218.801717974238, 5929.495992840657, 5937.9406205937385, 5937.960856850034, 5948.494377977834, 5957.970210148921, 5392.433794451605, 5450.482204190595, 5564.19319198722, 5639.3232812062815, 5651.180578816049, 4079.4296452947233, 4129.669626429878, 4135.1316223593385, 4168.353905144647, 4169.145325208325, 1134.914881383883, 1138.5502726423183, 1141.6061458796703, 1153.6250718516517, 1158.6350299655583, 1088.7441451258949, 1104.4808939798281, 1106.44124717019, 1110.9888901111046, 1114.5151858941224, 7457.142428620669, 7488.925110748932, 7500.249975002565, 7537.54774713519, 7635.503692259986, 1042.135027926819, 1050.5482642463662, 1052.752603138262, 1060.2101434431397, 1071.1484369925927, 4670.929936050962, 4857.635677324215, 4914.164592019365, 4918.631102667314, 4953.603711703127, 14056.367463464168, 14847.109173449628, 14871.47949821751, 14880.479183228535, 14897.329620167118, 13308.467661293633, 13781.310934453237, 14737.705245895022, 14738.926305368619, 14753.483628072965, 10760.46197690128, 11008.39453830031, 11046.944765276283, 11056.778864422666, 11059.336439813476, 7414.0, 7647.38820894316, 7681.15507294186, 7737.690492380467, 7775.650095745592, 12745.936270318589, 12818.67953301175, 12875.484980600671, 12924.159929604546, 13129.671758206121, 13196.868031319947, 13208.867911321147, 13215.537456189371, 13287.667808304483, 13313.933430332787, 10016.449095299828, 10274.486275686037, 10277.691669249934, 10295.07344339013, 10304.845427318745, 11081.39052352096, 11261.887381126411, 11537.077033837106, 11539.192256542297, 11695.532178712756, 9121.270298376247, 9134.817303653757, 9166.358354915359, 9185.77035574116, 9334.813303734023, 9306.534673266446, 9398.248140148908, 9470.57382417779, 9508.096730810563, 9542.42745435276, 7858.0, 7874.606269686607, 7919.08930473003, 7944.324732397811, 7992.080910695112, 6079.240094988127, 6114.425974336965, 6172.969135154295, 6234.251889773256, 6312.116303717575, 6474.805755827333, 6479.319671434431, 6494.090827284279, 6589.571677840925, 6668.499862510428], "value": 5496.8232715693875, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.1639894808806, 430.53072151355025, 440.1680823244067, 444.93548435476856, 446.1768037969944, 5179.274901513786, 5241.633085684004, 5252.60605454591, 5264.552513036397, 5266.736663166841, 4981.227909673998, 5128.769205385757, 5187.507186817246, 5190.480951904808, 5191.688498690081, 4333.826646934119, 4383.641071267917, 4387.407699960512, 4413.587651951377, 4421.0, 4419.292913133899, 4428.557144285578, 4440.4893437254805, 4448.4884238312525, 4460.687751857371, 4389.539098394671, 4428.822847086113, 4440.866773996785, 4495.752123938031, 4507.01691255848, 4389.648828093761, 4397.406350142722, 4407.669424793137, 4430.379746835446, 4447.5552444755585, 4157.708960372775, 4166.7708276044805, 4177.725793632941, 4178.582141785828, 4200.327947528412, 5098.796048158069, 5137.717425541594, 5139.408967968657, 5171.663841850305, 5186.521841275218, 4955.405351357824, 5010.3737032870895, 5024.598032157419, 5049.596032317406, 5112.156494178456, 5398.919016214721, 5440.755166017539, 5478.753456094485, 5486.396496385394, 5510.338759348863, 4480.417545719036, 4517.9774101129615, 4537.251353526664, 4572.474165470947, 4588.977055114737, 5387.676739395649, 5445.50990410861, 5538.584606154514, 5542.334919809568, 5685.658860468385, 4670.6497012724185, 4679.64290355793, 4697.224957881979, 4709.717416954925, 4751.216049351888, 4813.066606728132, 4839.685420447659, 4848.660593758474, 4853.417589889165, 4868.513148685174, 4599.218132917412, 4769.73766442849, 4773.665843390999, 4968.826091086795, 5126.820561280338, 5350.678959262456, 5400.864978375572, 5428.37573679032, 5449.9562664001, 5481.260029895992, 1614.5963509122719, 1616.822149563547, 1620.7892973913551, 1621.9026858388534, 1652.6942515634646, 1224.797908345131, 1254.4659787229928, 1285.3894400160036, 1288.7229245712028, 1307.6600083978235, 5246.366195010935, 5384.326959130109, 5446.7276636168035, 5462.508374246337, 5498.5876059294915, 5523.171524271311, 5526.640768350045, 5549.500544950974, 5555.666660000332, 5558.77764889408, 5357.1696387060065, 5373.838784836461, 5377.596680248997, 5379.838604841861, 5397.379301380313, 2780.610714499931, 2914.3879785245335, 2923.7807164462324, 2943.8528073596235, 2956.733893949555, 5795.275590551181, 5804.709764511757, 5805.593608447368, 5808.344621782771, 5823.941760582425, 5631.6902570359025, 5682.801101961411, 5687.9431205687415, 5704.885902282014, 5713.457221563945, 5556.055470570012, 5812.622179558315, 5856.355800861902, 5869.941300587025, 5907.493589134743, 5623.409541998195, 5638.661680299195, 5643.915341269802, 5656.830295091154, 5659.971700141473, 5366.838994830161, 5540.695261760642, 5549.38956714753, 5562.666240025612, 5611.943880561143, 5836.299644042742, 5838.708064596838, 5861.267341582302, 5867.383924687846, 5869.8239052828485, 4691.366665500251, 4749.28760685886, 4767.928481072718, 4769.761511924459, 4791.401074865642, 5485.573750824894, 5566.832995010156, 5569.387367389665, 5616.1856530803625, 5649.858753531194, 5418.485243901823, 5435.184722291529, 5449.711121329131, 5470.124780035176, 5477.4796394342475, 5248.9737551312, 5469.890602188014, 5483.287172667528, 5488.560915126701, 5525.76206636313, 5443.836684899459, 5449.809256675997, 5474.863128421821, 5499.972500137474, 5548.167774833807, 5546.306711661043, 5573.164025396223, 5581.627808181588, 5597.272354593877, 5613.989481893298, 5213.556847667991, 5423.376311724202, 5442.306058501067, 5454.699991500347, 5457.754401052041], "value": 4422.770178730885, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [843.6203708331245, 864.9264812490937, 903.6927444668815, 912.4662072687479, 931.3015238571072, 16750.74873876893, 17853.19660615274, 17864.732029019542, 17942.641147177048, 17982.91008544957, 12465.439055242514, 16547.089910054972, 16600.25298861551, 16705.498835034938, 16715.582110447245, 890.0, 909.9545022748869, 912.3522299167599, 915.1946287267228, 915.5879854065664, 911.3301723233451, 912.6623149434719, 912.726182145357, 913.0321858829628, 913.0777914306532, 955.5604421965869, 956.0964888180672, 956.1538038835611, 956.5647630328184, 957.3394357893067, 950.34901092751, 950.3870003847479, 950.3870003847549, 950.7385468996047, 950.9239260859115, 696.5716084607981, 697.3305626598471, 697.8604279144191, 698.6192525073797, 698.9615571143585, 16294.185290735415, 16374.918125409296, 16386.0, 16453.25960331776, 16568.0, 16439.0, 16684.499465015946, 16861.494155175365, 17058.0, 17624.383146589935, 13549.187048777105, 13826.0, 13836.169829810244, 13897.0, 13900.860991390058, 4014.698897582664, 4031.1502515098305, 4076.164386300801, 4135.1729654069, 4147.523034851, 53814.46185538173, 54269.0, 66409.0, 68065.65967170133, 70335.94496082613, 12035.819462708067, 12358.62924112278, 12650.746985060247, 12661.240325580495, 12747.42636581356, 23706.88146559256, 23834.16580419677, 23973.7602623975, 23990.0, 23994.88002559976, 8973.775655608531, 8975.506347150838, 9071.637134514678, 9082.636694532277, 9090.36546837163, 5026.748662566857, 5040.873978150502, 5065.569426598707, 5099.311592934981, 5216.035033518811, 3671.3391589514144, 3740.495033170542, 3745.850165993384, 3748.5876553579083, 3749.2876353492748, 652.7160685101995, 665.215046245435, 667.359335038359, 674.6222115615174, 678.7997540725498, 785.4462603864262, 814.0841553252591, 814.5866920892247, 827.6896163938524, 831.550962480266, 11229.550817967352, 11470.139739519569, 11763.941180294043, 11936.58221962227, 12020.519179232733, 762.1958833430783, 778.9766307010799, 790.2255789326377, 792.8374683189933, 802.9036515618163, 5264.92102618461, 5277.472252774692, 5391.433899440581, 5399.973000134974, 5411.675299482044, 76348.61825690836, 78687.60656196797, 81569.0, 82342.58828705818, 84186.57906710428, 43009.56990430056, 58949.70525147347, 68846.311536885, 68857.65571172112, 69327.65336173287, 62631.0, 62651.37348626547, 62844.05733913995, 63047.054294185626, 63365.68317158385, 11264.380459074502, 11550.191486596193, 11580.594679186188, 11763.411829408666, 11930.463129159036, 37637.81181094077, 38501.80749096237, 40714.59285407108, 42450.78774606107, 42494.36258456187, 50374.2443863335, 54272.0, 55147.0, 55886.72056639691, 55957.0, 14444.0, 14528.85471145275, 14567.635809104857, 14654.63363415923, 14736.189509577076, 16420.917895410446, 16756.74864877052, 17001.0, 17424.73862892083, 17627.0, 15072.0, 15150.469733559272, 15601.453949112181, 15714.2928568217, 16141.273642685872, 14595.562133136023, 15064.623384415478, 15382.307796149424, 15521.68956620884, 15864.92067539655, 15387.38450461969, 15783.921080394524, 16136.838631614004, 16178.352865885703, 16770.32918683239, 10592.464092706668, 11672.533098676298, 12007.339596322287, 12069.0, 12282.87717122853, 12938.417771200131, 13109.672258193621, 13159.144655597356, 13225.140365876186, 13249.583079519283], "value": 7900.12905572181, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1451.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 110.0, 112.0, 112.0, 112.0, 111.0, 110.0, 111.0, 112.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 124.0, 116.0, 145.0, 116.0, 115.0, 115.0, 119.0, 134.0, 115.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 161.0, 161.0, 160.0, 162.0, 160.0, 161.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 76.0, 114.0, 76.0, 76.0, 75.0, 76.0, 90.0, 75.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 106.0, 82.0, 83.0, 82.0, 93.0, 82.0, 82.0, 112.0], "value": 88.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 112.0, 112.0], "value": 112.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 57.0, 55.0, 53.0, 53.0, 55.0, 55.0, 54.0, 54.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 54.0, 53.0, 52.0, 52.0, 53.0, 53.0, 53.0, 53.0], "value": 52.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 79.0, 76.0, 83.0, 76.0, 75.0, 75.0, 83.0, 75.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 123.0, 121.0, 128.0, 135.0, 119.0, 118.0, 160.0, 117.0], "value": 128.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 159.0, 160.0, 165.0, 158.0, 159.0, 166.0, 159.0, 160.0, 156.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 59.0, 64.0, 59.0, 59.0, 58.0, 57.0], "value": 59.5, "unit": "score"}]}]} 17:36:14 INFO - Return code: 0 17:36:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:36:14 ERROR - # TBPL SUCCESS # 17:36:14 INFO - Running post-action listener: _resource_record_post_action 17:36:14 INFO - [mozharness: 2017-04-06 00:36:14.200995Z] Finished run-tests step (success) 17:36:14 INFO - Running post-run listener: _resource_record_post_run 17:36:14 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 266240; Write bytes: 362233856; Read time: 24; Write time: 360076 17:36:14 INFO - TinderboxPrint: CPU usage
13.1% 17:36:14 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 17:36:14 INFO - TinderboxPrint: I/O write bytes / time
362,233,856 / 360,076 17:36:14 INFO - TinderboxPrint: CPU idle
8,257.4 (86.9%) 17:36:14 INFO - TinderboxPrint: CPU user
1,202.5 (12.6%) 17:36:14 INFO - TinderboxPrint: Swap in / out
0 / 0 17:36:14 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 112283648; Read time: 0; Write time: 151272 17:36:14 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249950208; Read time: 24; Write time: 208804 17:36:14 INFO - Running post-run listener: _upload_blobber_files 17:36:14 INFO - Blob upload gear active. 17:36:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:36:14 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 17:36:14 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'] 17:36:14 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 17:36:15 INFO - (blobuploader) - INFO - Open directory for files ... 17:36:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:36:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:36:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:15 INFO - (blobuploader) - INFO - Done attempting. 17:36:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:36:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:36:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:16 INFO - (blobuploader) - INFO - Done attempting. 17:36:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:36:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:36:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:19 INFO - (blobuploader) - INFO - Done attempting. 17:36:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:36:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:36:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:20 INFO - (blobuploader) - INFO - Done attempting. 17:36:20 INFO - (blobuploader) - INFO - Iteration through files over. 17:36:20 INFO - Return code: 0 17:36:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:36:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:36:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e2600ff7546142c8ec3174c818e71e18b73de3ae59f17d5664ce77933a22b7dc9a4c20927c144ed7f914689056b5f173544c239cff7503455ff3851663a81230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/37f26ef192d81cc3d9262958814843c0666a000037caae24426bba6934de5976633f53b3a77ef1ddb03667315b9c52e45d4be307df8a7a30b31e75a56be7f292", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e794564989b7d13b4e63e037ff574fa1909085ff5d1264ad07b6d3db84636119b636f0cba73beb4f651c0eae62afceee7ae46fac2734949a408ed0cdd52b078f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fef92a77fdb69c7268cf86bf540db28a470caa210b8d4110de36ba185f5ff4e36c704f5a65efd328d44613d700b80b176fc7f059a828fd39f451213fffcfc03a"} 17:36:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:36:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:36:20 INFO - Contents: 17:36:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e2600ff7546142c8ec3174c818e71e18b73de3ae59f17d5664ce77933a22b7dc9a4c20927c144ed7f914689056b5f173544c239cff7503455ff3851663a81230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/37f26ef192d81cc3d9262958814843c0666a000037caae24426bba6934de5976633f53b3a77ef1ddb03667315b9c52e45d4be307df8a7a30b31e75a56be7f292", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e794564989b7d13b4e63e037ff574fa1909085ff5d1264ad07b6d3db84636119b636f0cba73beb4f651c0eae62afceee7ae46fac2734949a408ed0cdd52b078f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fef92a77fdb69c7268cf86bf540db28a470caa210b8d4110de36ba185f5ff4e36c704f5a65efd328d44613d700b80b176fc7f059a828fd39f451213fffcfc03a"} 17:36:20 INFO - Running post-run listener: copy_logs_to_upload_dir 17:36:20 INFO - Copying logs to upload dir... 17:36:20 INFO - mkdir: /builds/slave/test/build/upload/logs 17:36:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.630384 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-04-05 17:36:20.652147) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 17:36:20.656025) ========= 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=62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642 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/e2600ff7546142c8ec3174c818e71e18b73de3ae59f17d5664ce77933a22b7dc9a4c20927c144ed7f914689056b5f173544c239cff7503455ff3851663a81230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/37f26ef192d81cc3d9262958814843c0666a000037caae24426bba6934de5976633f53b3a77ef1ddb03667315b9c52e45d4be307df8a7a30b31e75a56be7f292", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e794564989b7d13b4e63e037ff574fa1909085ff5d1264ad07b6d3db84636119b636f0cba73beb4f651c0eae62afceee7ae46fac2734949a408ed0cdd52b078f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fef92a77fdb69c7268cf86bf540db28a470caa210b8d4110de36ba185f5ff4e36c704f5a65efd328d44613d700b80b176fc7f059a828fd39f451213fffcfc03a"} build_url:https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011114 build_url: 'https://queue.taskcluster.net/v1/task/B4B12CEYQP-axvULZf_eHQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e2600ff7546142c8ec3174c818e71e18b73de3ae59f17d5664ce77933a22b7dc9a4c20927c144ed7f914689056b5f173544c239cff7503455ff3851663a81230", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/37f26ef192d81cc3d9262958814843c0666a000037caae24426bba6934de5976633f53b3a77ef1ddb03667315b9c52e45d4be307df8a7a30b31e75a56be7f292", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/e794564989b7d13b4e63e037ff574fa1909085ff5d1264ad07b6d3db84636119b636f0cba73beb4f651c0eae62afceee7ae46fac2734949a408ed0cdd52b078f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/fef92a77fdb69c7268cf86bf540db28a470caa210b8d4110de36ba185f5ff4e36c704f5a65efd328d44613d700b80b176fc7f059a828fd39f451213fffcfc03a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 17:36:20.682138) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 17:36:20.682502) ========= 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=62c568580acbfdaac3eec7be000001dc-1491437729.216555-238635642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004399 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 17:36:20.766889) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 17:36:20.767244) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 17:36:20.767574) ========= ========= Total master_lag: 0.18 =========