builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-054 starttime: 1491467752.25 results: success (0) revision: f09f78db26a594b76139cee19a2487f9509d92ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:52.252684) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:52.253117) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:52.253388) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:52.267452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:52.267712) ========= 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=cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-06 01:35:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 216M=0s 2017-04-06 01:35:52 (216 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.098872 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:52.380166) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:52.380471) ========= 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=cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015445 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:52.429615) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 01:35:52.429911) ========= 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=cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-06 01:35:52,616 truncating revision to first 12 chars 2017-04-06 01:35:52,616 Setting DEBUG logging. 2017-04-06 01:35:52,616 attempt 1/10 2017-04-06 01:35:52,616 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-06 01:35:53,524 unpacking tar archive at: mozilla-aurora-f09f78db26a5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.248365 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-06 01:35:53.693421) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:53.693738) ========= script_repo_revision: f09f78db26a594b76139cee19a2487f9509d92ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:53.694100) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:53.694374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-06 01:35:53.704174) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-04-06 01:35:53.704451) ========= /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=cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:35:53 INFO - MultiFileLogger online at 20170406 01:35:53 in /builds/slave/test 01:35:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 01:35:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:35:53 INFO - {'append_to_log': False, 01:35:53 INFO - 'base_work_dir': '/builds/slave/test', 01:35:53 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 01:35:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:35:53 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 01:35:53 INFO - 'buildbot_json_path': 'buildprops.json', 01:35:53 INFO - 'config_files': ('talos/linux_config.py',), 01:35:53 INFO - 'default_actions': ('clobber', 01:35:53 INFO - 'read-buildbot-config', 01:35:53 INFO - 'download-and-extract', 01:35:53 INFO - 'populate-webroot', 01:35:53 INFO - 'create-virtualenv', 01:35:53 INFO - 'install', 01:35:53 INFO - 'run-tests'), 01:35:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:35:53 INFO - 'download_minidump_stackwalk': True, 01:35:53 INFO - 'download_symbols': 'ondemand', 01:35:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:35:53 INFO - 'tooltool.py': '/tools/tooltool.py', 01:35:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:35:53 INFO - '/tools/misc-python/virtualenv.py')}, 01:35:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:35:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:35:53 INFO - 'gecko_profile': False, 01:35:53 INFO - 'gecko_profile_interval': 0, 01:35:53 INFO - 'installer_path': 'installer.exe', 01:35:53 INFO - 'log_level': 'info', 01:35:53 INFO - 'log_name': 'talos', 01:35:53 INFO - 'log_to_console': True, 01:35:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:35:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:35:53 INFO - 'opt_config_files': (), 01:35:53 INFO - 'pip_index': False, 01:35:53 INFO - 'suite': 'dromaeojs', 01:35:53 INFO - 'system_bits': '32', 01:35:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:35:53 INFO - 'title': 'talos-linux64-ix-054', 01:35:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:35:53 INFO - 'use_talos_json': True, 01:35:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:35:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:35:53 INFO - 'work_dir': 'build'} 01:35:53 INFO - [mozharness: 2017-04-06 08:35:53.982062Z] Running clobber step. 01:35:53 INFO - Running pre-action listener: _resource_record_pre_action 01:35:53 INFO - Running main action method: clobber 01:35:53 INFO - rmtree: /builds/slave/test/build 01:35:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:35:55 INFO - Running post-action listener: _resource_record_post_action 01:35:55 INFO - [mozharness: 2017-04-06 08:35:55.500458Z] Finished clobber step (success) 01:35:55 INFO - [mozharness: 2017-04-06 08:35:55.500555Z] Running read-buildbot-config step. 01:35:55 INFO - Running pre-action listener: _resource_record_pre_action 01:35:55 INFO - Running main action method: read_buildbot_config 01:35:55 INFO - Using buildbot properties: 01:35:55 INFO - { 01:35:55 INFO - "project": "", 01:35:55 INFO - "product": "firefox", 01:35:55 INFO - "who": "nobody@mozilla.org", 01:35:55 INFO - "installer_path": "public/build/target.tar.bz2", 01:35:55 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 01:35:55 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora", 01:35:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 01:35:55 INFO - "stage_platform": "linux64", 01:35:55 INFO - "basedir": "/builds/slave/test", 01:35:55 INFO - "buildnumber": 111, 01:35:55 INFO - "platform": "ubuntu64_hw", 01:35:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:35:55 INFO - "slavebuilddir": "test", 01:35:55 INFO - "taskId": "KUOdKktiToC_ttA1E9eNZQ", 01:35:55 INFO - "branch": "mozilla-aurora", 01:35:55 INFO - "script_repo_revision": "production", 01:35:55 INFO - "revision": "f09f78db26a594b76139cee19a2487f9509d92ee", 01:35:55 INFO - "slavename": "talos-linux64-ix-054", 01:35:55 INFO - "repo_path": "releases/mozilla-aurora" 01:35:55 INFO - } 01:35:55 INFO - Finding installer, test and symbols from parent task. 01:35:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KUOdKktiToC_ttA1E9eNZQ'}, attempt #1 01:35:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KUOdKktiToC_ttA1E9eNZQ'}, attempt #1 01:35:56 INFO - Task dependencies: RxyyxJ8NSKmicp2hYbE_Tw 01:35:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw'}, attempt #1 01:35:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.tar.bz2 01:35:56 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/test_packages.json 01:35:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.crashreporter-symbols.zip 01:35:56 INFO - Running post-action listener: _resource_record_post_action 01:35:56 INFO - [mozharness: 2017-04-06 08:35:56.967515Z] Finished read-buildbot-config step (success) 01:35:56 INFO - [mozharness: 2017-04-06 08:35:56.967732Z] Running download-and-extract step. 01:35:56 INFO - Running pre-action listener: _resource_record_pre_action 01:35:56 INFO - Running main action method: download_and_extract 01:35:56 INFO - mkdir: /builds/slave/test/build/tests 01:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:35:56 INFO - trying https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.test_packages.json 01:35:56 INFO - Downloading https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 01:35:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 01:35:58 INFO - Downloaded 896 bytes. 01:35:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 01:35:58 INFO - Using the following test package requirements: 01:35:58 INFO - {u'common': [u'target.common.tests.zip'], 01:35:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 01:35:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 01:35:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 01:35:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 01:35:58 INFO - u'mozbase': [u'target.common.tests.zip'], 01:35:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 01:35:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 01:35:58 INFO - u'web-platform': [u'target.common.tests.zip', 01:35:58 INFO - u'target.web-platform.tests.zip'], 01:35:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 01:35:58 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 01:35:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.common.tests.zip 01:35:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:35:58 INFO - Fetch https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.common.tests.zip into memory 01:35:59 INFO - Expected file size: 59540471 01:35:59 INFO - Obtained file size: 59540471 01:36:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 01:36:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.common.tests.zip 01:36:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.common.tests.zip'}, attempt #1 01:36:09 INFO - Fetch https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.common.tests.zip into memory 01:36:16 INFO - Expected file size: 59540471 01:36:16 INFO - Obtained file size: 59540471 01:36:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.talos.tests.zip 01:36:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 01:36:27 INFO - Fetch https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.talos.tests.zip into memory 01:36:27 INFO - Expected file size: 14404438 01:36:27 INFO - Obtained file size: 14404438 01:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:28 INFO - trying https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.tar.bz2 01:36:28 INFO - Downloading https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 01:36:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 01:36:29 INFO - Downloaded 66714464 bytes. 01:36:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.tar.bz2 01:36:29 INFO - mkdir: /builds/slave/test/properties 01:36:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:36:29 INFO - Writing to file /builds/slave/test/properties/build_url 01:36:29 INFO - Contents: 01:36:29 INFO - build_url:https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.tar.bz2 01:36:29 INFO - Running post-action listener: _resource_record_post_action 01:36:29 INFO - Running post-action listener: set_extra_try_arguments 01:36:29 INFO - [mozharness: 2017-04-06 08:36:29.360370Z] Finished download-and-extract step (success) 01:36:29 INFO - [mozharness: 2017-04-06 08:36:29.360466Z] Running populate-webroot step. 01:36:29 INFO - Running pre-action listener: _resource_record_pre_action 01:36:29 INFO - Running main action method: populate_webroot 01:36:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:36:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:36:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:36:29 INFO - '--tppagecycles', 01:36:29 INFO - '7'], 01:36:29 INFO - 'tests': ['tsvgm']}}, 01:36:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:36:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:36:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:36:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:36:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:36:29 INFO - 'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:36:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:36:29 INFO - 'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['damp', 'tps']}, 01:36:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:36:29 INFO - 'tests': ['damp', 'tps']}, 01:36:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['dromaeo_dom']}, 01:36:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:36:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['basic_compositor_video']}, 01:36:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:36:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['a11yr', 01:36:29 INFO - 'ts_paint', 01:36:29 INFO - 'tpaint', 01:36:29 INFO - 'sessionrestore', 01:36:29 INFO - 'sessionrestore_no_auto_restore', 01:36:29 INFO - 'tabpaint']}, 01:36:29 INFO - 'other-e10s': {'tests': ['a11yr', 01:36:29 INFO - 'ts_paint', 01:36:29 INFO - 'tpaint', 01:36:29 INFO - 'sessionrestore', 01:36:29 INFO - 'sessionrestore_no_auto_restore', 01:36:29 INFO - 'tabpaint']}, 01:36:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:36:29 INFO - 'ts_paint', 01:36:29 INFO - 'tpaint', 01:36:29 INFO - 'sessionrestore', 01:36:29 INFO - 'sessionrestore_no_auto_restore', 01:36:29 INFO - 'tabpaint']}, 01:36:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:36:29 INFO - 'ts_paint', 01:36:29 INFO - 'tpaint', 01:36:29 INFO - 'sessionrestore', 01:36:29 INFO - 'sessionrestore_no_auto_restore', 01:36:29 INFO - 'tabpaint']}, 01:36:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['a11yr', 01:36:29 INFO - 'ts_paint', 01:36:29 INFO - 'tpaint', 01:36:29 INFO - 'sessionrestore', 01:36:29 INFO - 'sessionrestore_no_auto_restore', 01:36:29 INFO - 'tabpaint']}, 01:36:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['a11yr', 01:36:29 INFO - 'ts_paint', 01:36:29 INFO - 'tpaint', 01:36:29 INFO - 'sessionrestore', 01:36:29 INFO - 'sessionrestore_no_auto_restore', 01:36:29 INFO - 'tabpaint']}, 01:36:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['tsvgx', 01:36:29 INFO - 'tsvgr_opacity', 01:36:29 INFO - 'tart', 01:36:29 INFO - 'tscrollx', 01:36:29 INFO - 'cart', 01:36:29 INFO - 'tsvg_static']}, 01:36:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:36:29 INFO - 'tsvgr_opacity', 01:36:29 INFO - 'tart', 01:36:29 INFO - 'tscrollx', 01:36:29 INFO - 'cart', 01:36:29 INFO - 'tsvg_static']}, 01:36:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:36:29 INFO - 'talos_options': ['--disable-e10s'], 01:36:29 INFO - 'tests': ['tp5o']}, 01:36:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:36:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:36:29 INFO - 'talos_options': ['--disable-e10s', 01:36:29 INFO - '--xperf_path', 01:36:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:29 INFO - 'tests': ['tp5n']}, 01:36:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:36:29 INFO - 'talos_options': ['--xperf_path', 01:36:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:29 INFO - 'tests': ['tp5n']}}, 01:36:29 INFO - 'talos.zip': {'path': '', 01:36:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:36:29 INFO - Running post-action listener: _resource_record_post_action 01:36:29 INFO - [mozharness: 2017-04-06 08:36:29.373592Z] Finished populate-webroot step (success) 01:36:29 INFO - [mozharness: 2017-04-06 08:36:29.373682Z] Running create-virtualenv step. 01:36:29 INFO - Running pre-action listener: _resource_record_pre_action 01:36:29 INFO - Running main action method: create_virtualenv 01:36:29 INFO - Creating virtualenv /builds/slave/test/build/venv 01:36:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:36:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:36:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:36:29 INFO - Using real prefix '/usr' 01:36:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:36:29 INFO - Installing distribute.............................................................................................................................................................................................done. 01:36:31 INFO - Installing pip.................done. 01:36:31 INFO - Return code: 0 01:36:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:36:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:36:32 INFO - Reading from file tmpfile_stdout 01:36:32 INFO - Output received: 01:36:32 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 01:36:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:36:32 INFO - Using env: {'DISPLAY': ':0', 01:36:32 INFO - 'HOME': '/home/cltbld', 01:36:32 INFO - 'LANG': 'en_US.UTF-8', 01:36:32 INFO - 'LANGUAGE': 'en_US:en', 01:36:32 INFO - 'LOGNAME': 'cltbld', 01:36:32 INFO - 'MAIL': '/var/mail/cltbld', 01:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:32 INFO - 'MOZ_NO_REMOTE': '1', 01:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:32 INFO - 'NO_EM_RESTART': '1', 01:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:32 INFO - 'PWD': '/builds/slave/test', 01:36:32 INFO - 'SHELL': '/bin/bash', 01:36:32 INFO - 'SHLVL': '1', 01:36:32 INFO - 'TERM': 'linux', 01:36:32 INFO - 'TMOUT': '86400', 01:36:32 INFO - 'USER': 'cltbld', 01:36:32 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:32 INFO - '_': '/tools/buildbot/bin/python'} 01:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:36:32 INFO - Cleaning up... 01:36:32 INFO - Return code: 0 01:36:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:36:32 INFO - Using env: {'DISPLAY': ':0', 01:36:32 INFO - 'HOME': '/home/cltbld', 01:36:32 INFO - 'LANG': 'en_US.UTF-8', 01:36:32 INFO - 'LANGUAGE': 'en_US:en', 01:36:32 INFO - 'LOGNAME': 'cltbld', 01:36:32 INFO - 'MAIL': '/var/mail/cltbld', 01:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:32 INFO - 'MOZ_NO_REMOTE': '1', 01:36:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:32 INFO - 'NO_EM_RESTART': '1', 01:36:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:32 INFO - 'PWD': '/builds/slave/test', 01:36:32 INFO - 'SHELL': '/bin/bash', 01:36:32 INFO - 'SHLVL': '1', 01:36:32 INFO - 'TERM': 'linux', 01:36:32 INFO - 'TMOUT': '86400', 01:36:32 INFO - 'USER': 'cltbld', 01:36:32 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:32 INFO - '_': '/tools/buildbot/bin/python'} 01:36:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:32 INFO - Downloading/unpacking psutil>=3.1.1 01:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:36:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:34 INFO - Installing collected packages: psutil 01:36:34 INFO - Running setup.py install for psutil 01:36:34 INFO - building 'psutil._psutil_linux' extension 01:36:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 01:36:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 01:36:34 INFO - building 'psutil._psutil_posix' extension 01:36:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 01:36:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 01:36:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:35 INFO - Successfully installed psutil 01:36:35 INFO - Cleaning up... 01:36:35 INFO - Return code: 0 01:36:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:36:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:35 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 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:36:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:36:35 INFO - Using env: {'DISPLAY': ':0', 01:36:35 INFO - 'HOME': '/home/cltbld', 01:36:35 INFO - 'LANG': 'en_US.UTF-8', 01:36:35 INFO - 'LANGUAGE': 'en_US:en', 01:36:35 INFO - 'LOGNAME': 'cltbld', 01:36:35 INFO - 'MAIL': '/var/mail/cltbld', 01:36:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:35 INFO - 'MOZ_NO_REMOTE': '1', 01:36:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:35 INFO - 'NO_EM_RESTART': '1', 01:36:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:35 INFO - 'PWD': '/builds/slave/test', 01:36:35 INFO - 'SHELL': '/bin/bash', 01:36:35 INFO - 'SHLVL': '1', 01:36:35 INFO - 'TERM': 'linux', 01:36:35 INFO - 'TMOUT': '86400', 01:36:35 INFO - 'USER': 'cltbld', 01:36:35 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:35 INFO - '_': '/tools/buildbot/bin/python'} 01:36:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:36:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:36:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:36:37 INFO - Installing collected packages: mozsystemmonitor 01:36:37 INFO - Running setup.py install for mozsystemmonitor 01:36:37 INFO - Successfully installed mozsystemmonitor 01:36:37 INFO - Cleaning up... 01:36:37 INFO - Return code: 0 01:36:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:37 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 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:36:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:36:37 INFO - Using env: {'DISPLAY': ':0', 01:36:37 INFO - 'HOME': '/home/cltbld', 01:36:37 INFO - 'LANG': 'en_US.UTF-8', 01:36:37 INFO - 'LANGUAGE': 'en_US:en', 01:36:37 INFO - 'LOGNAME': 'cltbld', 01:36:37 INFO - 'MAIL': '/var/mail/cltbld', 01:36:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:37 INFO - 'MOZ_NO_REMOTE': '1', 01:36:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:37 INFO - 'NO_EM_RESTART': '1', 01:36:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:37 INFO - 'PWD': '/builds/slave/test', 01:36:37 INFO - 'SHELL': '/bin/bash', 01:36:37 INFO - 'SHLVL': '1', 01:36:37 INFO - 'TERM': 'linux', 01:36:37 INFO - 'TMOUT': '86400', 01:36:37 INFO - 'USER': 'cltbld', 01:36:37 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:37 INFO - '_': '/tools/buildbot/bin/python'} 01:36:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:37 INFO - Downloading/unpacking jsonschema==2.5.1 01:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:36:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:40 INFO - Downloading functools32-3.2.3-2.tar.gz 01:36:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:36:40 INFO - warning: no files found matching '*.txt' 01:36:40 INFO - no previously-included directories found matching 'build' 01:36:40 INFO - no previously-included directories found matching 'dist' 01:36:40 INFO - no previously-included directories found matching '.git*' 01:36:40 INFO - Installing collected packages: jsonschema, functools32 01:36:40 INFO - Running setup.py install for functools32 01:36:40 INFO - warning: no files found matching '*.txt' 01:36:40 INFO - no previously-included directories found matching 'build' 01:36:40 INFO - no previously-included directories found matching 'dist' 01:36:40 INFO - no previously-included directories found matching '.git*' 01:36:40 INFO - Successfully installed jsonschema functools32 01:36:40 INFO - Cleaning up... 01:36:40 INFO - Return code: 0 01:36:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:40 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 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:36:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:36:40 INFO - Using env: {'DISPLAY': ':0', 01:36:40 INFO - 'HOME': '/home/cltbld', 01:36:40 INFO - 'LANG': 'en_US.UTF-8', 01:36:40 INFO - 'LANGUAGE': 'en_US:en', 01:36:40 INFO - 'LOGNAME': 'cltbld', 01:36:40 INFO - 'MAIL': '/var/mail/cltbld', 01:36:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:40 INFO - 'MOZ_NO_REMOTE': '1', 01:36:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:40 INFO - 'NO_EM_RESTART': '1', 01:36:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:40 INFO - 'PWD': '/builds/slave/test', 01:36:40 INFO - 'SHELL': '/bin/bash', 01:36:40 INFO - 'SHLVL': '1', 01:36:40 INFO - 'TERM': 'linux', 01:36:40 INFO - 'TMOUT': '86400', 01:36:40 INFO - 'USER': 'cltbld', 01:36:40 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:40 INFO - '_': '/tools/buildbot/bin/python'} 01:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:36:40 INFO - Cleaning up... 01:36:40 INFO - Return code: 0 01:36:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:40 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 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:36:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:36:40 INFO - Using env: {'DISPLAY': ':0', 01:36:40 INFO - 'HOME': '/home/cltbld', 01:36:40 INFO - 'LANG': 'en_US.UTF-8', 01:36:40 INFO - 'LANGUAGE': 'en_US:en', 01:36:40 INFO - 'LOGNAME': 'cltbld', 01:36:40 INFO - 'MAIL': '/var/mail/cltbld', 01:36:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:40 INFO - 'MOZ_NO_REMOTE': '1', 01:36:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:40 INFO - 'NO_EM_RESTART': '1', 01:36:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:40 INFO - 'PWD': '/builds/slave/test', 01:36:40 INFO - 'SHELL': '/bin/bash', 01:36:40 INFO - 'SHLVL': '1', 01:36:40 INFO - 'TERM': 'linux', 01:36:40 INFO - 'TMOUT': '86400', 01:36:40 INFO - 'USER': 'cltbld', 01:36:40 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:40 INFO - '_': '/tools/buildbot/bin/python'} 01:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:40 INFO - Downloading/unpacking blobuploader==1.2.4 01:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:42 INFO - Downloading blobuploader-1.2.4.tar.gz 01:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:36:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:36:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:43 INFO - Downloading docopt-0.6.1.tar.gz 01:36:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:36:43 INFO - Installing collected packages: blobuploader, requests, docopt 01:36:43 INFO - Running setup.py install for blobuploader 01:36:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:36:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:36:43 INFO - Running setup.py install for requests 01:36:44 INFO - Running setup.py install for docopt 01:36:44 INFO - Successfully installed blobuploader requests docopt 01:36:44 INFO - Cleaning up... 01:36:44 INFO - Return code: 0 01:36:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:44 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 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:36:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:36:44 INFO - Using env: {'DISPLAY': ':0', 01:36:44 INFO - 'HOME': '/home/cltbld', 01:36:44 INFO - 'LANG': 'en_US.UTF-8', 01:36:44 INFO - 'LANGUAGE': 'en_US:en', 01:36:44 INFO - 'LOGNAME': 'cltbld', 01:36:44 INFO - 'MAIL': '/var/mail/cltbld', 01:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:44 INFO - 'MOZ_NO_REMOTE': '1', 01:36:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:44 INFO - 'NO_EM_RESTART': '1', 01:36:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:44 INFO - 'PWD': '/builds/slave/test', 01:36:44 INFO - 'SHELL': '/bin/bash', 01:36:44 INFO - 'SHLVL': '1', 01:36:44 INFO - 'TERM': 'linux', 01:36:44 INFO - 'TMOUT': '86400', 01:36:44 INFO - 'USER': 'cltbld', 01:36:44 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:44 INFO - '_': '/tools/buildbot/bin/python'} 01:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:36:44 INFO - Running setup.py (path:/tmp/pip-n56lMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:36:44 INFO - Running setup.py (path:/tmp/pip-UhpLOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:36:44 INFO - Running setup.py (path:/tmp/pip-PyzqL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:36:44 INFO - Running setup.py (path:/tmp/pip-jrJMkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:36:44 INFO - Running setup.py (path:/tmp/pip-CU3cst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:36:44 INFO - Running setup.py (path:/tmp/pip-vrCT0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:36:45 INFO - Running setup.py (path:/tmp/pip-IEbGOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:36:45 INFO - Running setup.py (path:/tmp/pip-vXYmFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:36:45 INFO - Running setup.py (path:/tmp/pip-bI4K6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:36:45 INFO - Running setup.py (path:/tmp/pip-jb62Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:36:45 INFO - Running setup.py (path:/tmp/pip-q6UfgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:36:45 INFO - Running setup.py (path:/tmp/pip-6H5gio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:36:45 INFO - Running setup.py (path:/tmp/pip-1nnylD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:36:45 INFO - Running setup.py (path:/tmp/pip-qROtlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:45 INFO - Running setup.py (path:/tmp/pip-Vqg00k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:36:45 INFO - Running setup.py (path:/tmp/pip-3DPKi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:36:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:36:45 INFO - Running setup.py (path:/tmp/pip-qLy6e9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:36:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:36:45 INFO - Running setup.py install for manifestparser 01:36:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:36:45 INFO - Running setup.py install for mozcrash 01:36:46 INFO - Running setup.py install for mozdebug 01:36:46 INFO - Running setup.py install for mozdevice 01:36:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Running setup.py install for mozfile 01:36:46 INFO - Running setup.py install for mozhttpd 01:36:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Running setup.py install for mozinfo 01:36:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Running setup.py install for mozInstall 01:36:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Running setup.py install for mozleak 01:36:46 INFO - Running setup.py install for mozlog 01:36:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Running setup.py install for moznetwork 01:36:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:36:46 INFO - Running setup.py install for mozprocess 01:36:47 INFO - Running setup.py install for mozprofile 01:36:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:36:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:36:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:36:47 INFO - Running setup.py install for mozrunner 01:36:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:36:47 INFO - Running setup.py install for mozscreenshot 01:36:47 INFO - Running setup.py install for moztest 01:36:47 INFO - Running setup.py install for mozversion 01:36:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:36:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:36:47 INFO - Cleaning up... 01:36:47 INFO - Return code: 0 01:36:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:47 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 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:36:47 INFO - Using env: {'DISPLAY': ':0', 01:36:47 INFO - 'HOME': '/home/cltbld', 01:36:47 INFO - 'LANG': 'en_US.UTF-8', 01:36:47 INFO - 'LANGUAGE': 'en_US:en', 01:36:47 INFO - 'LOGNAME': 'cltbld', 01:36:47 INFO - 'MAIL': '/var/mail/cltbld', 01:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:47 INFO - 'MOZ_NO_REMOTE': '1', 01:36:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:47 INFO - 'NO_EM_RESTART': '1', 01:36:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:47 INFO - 'PWD': '/builds/slave/test', 01:36:47 INFO - 'SHELL': '/bin/bash', 01:36:47 INFO - 'SHLVL': '1', 01:36:47 INFO - 'TERM': 'linux', 01:36:47 INFO - 'TMOUT': '86400', 01:36:47 INFO - 'USER': 'cltbld', 01:36:47 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:47 INFO - '_': '/tools/buildbot/bin/python'} 01:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:36:47 INFO - Running setup.py (path:/tmp/pip-kL7hRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:36:47 INFO - Running setup.py (path:/tmp/pip-uMtMCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:36:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:36:47 INFO - Running setup.py (path:/tmp/pip-BxLwam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:36:48 INFO - Running setup.py (path:/tmp/pip-YrQsAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:36:48 INFO - Running setup.py (path:/tmp/pip-cKEbWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:36:48 INFO - Running setup.py (path:/tmp/pip-zCRkfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:36:48 INFO - Running setup.py (path:/tmp/pip-skNpQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:36:48 INFO - Running setup.py (path:/tmp/pip-hb2A6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:36:48 INFO - Running setup.py (path:/tmp/pip-4lS6gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:36:48 INFO - Running setup.py (path:/tmp/pip-Gdq9Qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:36:48 INFO - Running setup.py (path:/tmp/pip-hi71b1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:36:48 INFO - Running setup.py (path:/tmp/pip-_hMCiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:36:48 INFO - Running setup.py (path:/tmp/pip-R7bNDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:36:48 INFO - Running setup.py (path:/tmp/pip-l9gKqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:48 INFO - Running setup.py (path:/tmp/pip-UvBKt7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:36:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:36:48 INFO - Running setup.py (path:/tmp/pip-zItZKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:36:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:36:49 INFO - Running setup.py (path:/tmp/pip-zzFGrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:36:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:36:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:51 INFO - Downloading blessings-1.6.tar.gz 01:36:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:36:51 INFO - Installing collected packages: blessings 01:36:51 INFO - Running setup.py install for blessings 01:36:51 INFO - Successfully installed blessings 01:36:51 INFO - Cleaning up... 01:36:51 INFO - Return code: 0 01:36:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:36:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:36:51 INFO - Reading from file tmpfile_stdout 01:36:51 INFO - Current package versions: 01:36:51 INFO - argparse == 1.2.1 01:36:51 INFO - blessings == 1.6 01:36:51 INFO - blobuploader == 1.2.4 01:36:51 INFO - docopt == 0.6.1 01:36:51 INFO - functools32 == 3.2.3-2 01:36:51 INFO - jsonschema == 2.5.1 01:36:51 INFO - manifestparser == 1.1 01:36:51 INFO - mozInstall == 1.12 01:36:51 INFO - mozcrash == 1.0 01:36:51 INFO - mozdebug == 0.1 01:36:51 INFO - mozdevice == 0.49 01:36:51 INFO - mozfile == 1.2 01:36:51 INFO - mozhttpd == 0.7 01:36:51 INFO - mozinfo == 0.9 01:36:51 INFO - mozleak == 0.1 01:36:51 INFO - mozlog == 3.4 01:36:51 INFO - moznetwork == 0.27 01:36:51 INFO - mozprocess == 0.25 01:36:51 INFO - mozprofile == 0.28 01:36:51 INFO - mozrunner == 6.13 01:36:51 INFO - mozscreenshot == 0.1 01:36:51 INFO - mozsystemmonitor == 0.3 01:36:51 INFO - moztest == 0.8 01:36:51 INFO - mozversion == 1.4 01:36:51 INFO - psutil == 3.1.1 01:36:51 INFO - requests == 1.2.3 01:36:51 INFO - wsgiref == 0.1.2 01:36:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:51 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 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:36:51 INFO - Using env: {'DISPLAY': ':0', 01:36:51 INFO - 'HOME': '/home/cltbld', 01:36:51 INFO - 'LANG': 'en_US.UTF-8', 01:36:51 INFO - 'LANGUAGE': 'en_US:en', 01:36:51 INFO - 'LOGNAME': 'cltbld', 01:36:51 INFO - 'MAIL': '/var/mail/cltbld', 01:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:51 INFO - 'MOZ_NO_REMOTE': '1', 01:36:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:51 INFO - 'NO_EM_RESTART': '1', 01:36:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:51 INFO - 'PWD': '/builds/slave/test', 01:36:51 INFO - 'SHELL': '/bin/bash', 01:36:51 INFO - 'SHLVL': '1', 01:36:51 INFO - 'TERM': 'linux', 01:36:51 INFO - 'TMOUT': '86400', 01:36:51 INFO - 'USER': 'cltbld', 01:36:51 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:51 INFO - '_': '/tools/buildbot/bin/python'} 01:36:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:36:51 INFO - Cleaning up... 01:36:51 INFO - Return code: 0 01:36:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:36:51 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 0x29861f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c24310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, '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': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:36:51 INFO - Using env: {'DISPLAY': ':0', 01:36:51 INFO - 'HOME': '/home/cltbld', 01:36:51 INFO - 'LANG': 'en_US.UTF-8', 01:36:51 INFO - 'LANGUAGE': 'en_US:en', 01:36:51 INFO - 'LOGNAME': 'cltbld', 01:36:51 INFO - 'MAIL': '/var/mail/cltbld', 01:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:51 INFO - 'MOZ_NO_REMOTE': '1', 01:36:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:36:51 INFO - 'NO_EM_RESTART': '1', 01:36:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:51 INFO - 'PWD': '/builds/slave/test', 01:36:51 INFO - 'SHELL': '/bin/bash', 01:36:51 INFO - 'SHLVL': '1', 01:36:51 INFO - 'TERM': 'linux', 01:36:51 INFO - 'TMOUT': '86400', 01:36:51 INFO - 'USER': 'cltbld', 01:36:51 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:51 INFO - '_': '/tools/buildbot/bin/python'} 01:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:36:52 INFO - Cleaning up... 01:36:52 INFO - Return code: 0 01:36:52 INFO - Running post-action listener: _resource_record_post_action 01:36:52 INFO - Running post-action listener: _start_resource_monitoring 01:36:52 INFO - Starting resource monitoring. 01:36:52 INFO - [mozharness: 2017-04-06 08:36:52.075764Z] Finished create-virtualenv step (success) 01:36:52 INFO - [mozharness: 2017-04-06 08:36:52.076274Z] Running install step. 01:36:52 INFO - Running pre-action listener: _resource_record_pre_action 01:36:52 INFO - Running main action method: install 01:36:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:36:52 INFO - Reading from file tmpfile_stdout 01:36:52 INFO - Detecting whether we're running mozinstall >=1.0... 01:36:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:36:52 INFO - Reading from file tmpfile_stdout 01:36:52 INFO - Output received: 01:36:52 INFO - Usage: mozinstall [options] installer 01:36:52 INFO - Options: 01:36:52 INFO - -h, --help show this help message and exit 01:36:52 INFO - -d DEST, --destination=DEST 01:36:52 INFO - Directory to install application into. [default: 01:36:52 INFO - "/builds/slave/test"] 01:36:52 INFO - --app=APP Application being installed. [default: firefox] 01:36:52 INFO - mkdir: /builds/slave/test/build/application 01:36:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 01:37:08 INFO - Reading from file tmpfile_stdout 01:37:08 INFO - Output received: 01:37:08 INFO - /builds/slave/test/build/application/firefox/firefox 01:37:08 INFO - Running post-action listener: _resource_record_post_action 01:37:08 INFO - [mozharness: 2017-04-06 08:37:08.932137Z] Finished install step (success) 01:37:08 INFO - [mozharness: 2017-04-06 08:37:08.932410Z] Running run-tests step. 01:37:08 INFO - Running pre-action listener: _resource_record_pre_action 01:37:08 INFO - Running main action method: run_tests 01:37:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:37:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:37:08 INFO - Python 2.7.3 01:37:08 INFO - Return code: 0 01:37:08 INFO - grabbing minidump binary from tooltool 01:37:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:08 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 0x2c21400>, '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 0x2c223b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c22840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:37:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:37:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 01:37:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:37:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:37:09 INFO - Return code: 0 01:37:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:37:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:37:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:37:09 INFO - ENV: RUST_BACKTRACE is now 1 01:37:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:37:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:37:09 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/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:37:09 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/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:37:09 INFO - Using env: {'DISPLAY': ':0', 01:37:09 INFO - 'HOME': '/home/cltbld', 01:37:09 INFO - 'LANG': 'en_US.UTF-8', 01:37:09 INFO - 'LANGUAGE': 'en_US:en', 01:37:09 INFO - 'LOGNAME': 'cltbld', 01:37:09 INFO - 'MAIL': '/var/mail/cltbld', 01:37:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:37:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:37:09 INFO - 'MOZ_NO_REMOTE': '1', 01:37:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:37:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:37:09 INFO - 'NO_EM_RESTART': '1', 01:37:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:37:09 INFO - 'PWD': '/builds/slave/test', 01:37:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:37:09 INFO - 'RUST_BACKTRACE': '1', 01:37:09 INFO - 'SHELL': '/bin/bash', 01:37:09 INFO - 'SHLVL': '1', 01:37:09 INFO - 'TERM': 'linux', 01:37:09 INFO - 'TMOUT': '86400', 01:37:09 INFO - 'USER': 'cltbld', 01:37:09 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679', 01:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:37:09 INFO - '_': '/tools/buildbot/bin/python'} 01:37:09 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/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:37:09 INFO - mozversion application_buildid: 20170406074732 01:37:09 INFO - mozversion application_changeset: f09f78db26a594b76139cee19a2487f9509d92ee 01:37:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:37:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:37:09 INFO - mozversion application_name: Firefox 01:37:09 INFO - mozversion application_remotingname: firefox-dev 01:37:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:37:09 INFO - mozversion application_vendor: Mozilla 01:37:09 INFO - mozversion application_version: 54.0a2 01:37:09 INFO - mozversion platform_buildid: 20170406074732 01:37:09 INFO - mozversion platform_changeset: f09f78db26a594b76139cee19a2487f9509d92ee 01:37:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:37:09 INFO - mozversion platform_version: 54.0a2 01:37:09 INFO - using testdate: 1491467829 01:37:09 INFO - actual date: 1491467829 01:37:09 INFO - starting webserver on 'localhost:42892' 01:37:09 INFO - SUITE-START | Running 2 tests 01:37:09 INFO - TEST-START | dromaeo_css 01:37:09 INFO - operating with platform_type : linux_ 01:37:09 INFO - Initialising browser for dromaeo_css test... 01:37:09 INFO - TEST-INFO | started process 9650 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoIqXep/profile http://localhost:42892/getInfo.html) 01:37:13 INFO - PROCESS | 9650 | __metrics Screen width/height:1600/1200 01:37:13 INFO - PROCESS | 9650 | colorDepth:24 01:37:13 INFO - PROCESS | 9650 | Browser inner width/height: 1024/697 01:37:13 INFO - PROCESS | 9650 | __metrics 01:37:14 INFO - PROCESS | 9650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:37:14 INFO - PROCESS | 9650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:37:15 INFO - PROCESS | 9650 | console.error: PushService: 01:37:15 INFO - PROCESS | 9650 | stateChangeProcessEnqueue: Error transitioning state 01:37:15 INFO - PROCESS | 9650 | UnknownError 01:37:15 INFO - TEST-INFO | 9650: exit 0 01:37:15 INFO - Browser initialized. 01:37:15 INFO - Running cycle 1/1 for dromaeo_css test... 01:37:15 INFO - TEST-INFO | started process 9744 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoIqXep/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:37:27 INFO - PROCESS | 9744 | RSS: Main: 140099584 01:37:27 INFO - PROCESS | 9744 | 01:40:28 INFO - PROCESS | 9744 | Cycle 1(1): loaded http://localhost:42892/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42892/tests/dromaeo/cssquery-ext.html) 01:40:30 INFO - PROCESS | 9744 | RSS: Main: 283340800 01:40:30 INFO - PROCESS | 9744 | 01:42:17 INFO - PROCESS | 9744 | ************************* 01:42:17 INFO - PROCESS | 9744 | A coding exception was thrown and uncaught in a Task. 01:42:17 INFO - PROCESS | 9744 | 01:42:17 ERROR - PROCESS | 9744 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:17 INFO - PROCESS | 9744 | Full stack: 01:42:17 INFO - PROCESS | 9744 | ************************* 01:42:17 INFO - PROCESS | 9744 | ************************* 01:42:17 INFO - PROCESS | 9744 | A coding exception was thrown and uncaught in a Task. 01:42:17 INFO - PROCESS | 9744 | 01:42:17 ERROR - PROCESS | 9744 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:17 INFO - PROCESS | 9744 | Full stack: 01:42:17 INFO - PROCESS | 9744 | ************************* 01:42:17 INFO - PROCESS | 9744 | ************************* 01:42:17 INFO - PROCESS | 9744 | A coding exception was thrown and uncaught in a Task. 01:42:17 INFO - PROCESS | 9744 | 01:42:17 ERROR - PROCESS | 9744 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:17 INFO - PROCESS | 9744 | Full stack: 01:42:17 INFO - PROCESS | 9744 | ************************* 01:42:17 INFO - PROCESS | 9744 | ************************* 01:42:17 INFO - PROCESS | 9744 | A coding exception was thrown and uncaught in a Task. 01:42:17 INFO - PROCESS | 9744 | 01:42:17 ERROR - PROCESS | 9744 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:42:17 INFO - PROCESS | 9744 | Full stack: 01:42:17 INFO - PROCESS | 9744 | ************************* 01:43:32 INFO - PROCESS | 9744 | Cycle 1(1): loaded http://localhost:42892/tests/dromaeo/cssquery-ext.html (next: http://localhost:42892/tests/dromaeo/cssquery-jquery.html) 01:43:33 INFO - PROCESS | 9744 | RSS: Main: 222937088 01:43:33 INFO - PROCESS | 9744 | 01:46:35 INFO - PROCESS | 9744 | Cycle 1(1): loaded http://localhost:42892/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42892/tests/dromaeo/cssquery-mootools.html) 01:46:35 INFO - PROCESS | 9744 | RSS: Main: 233926656 01:46:35 INFO - PROCESS | 9744 | 01:49:37 INFO - PROCESS | 9744 | Cycle 1(1): loaded http://localhost:42892/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42892/tests/dromaeo/cssquery-prototype.html) 01:49:38 INFO - PROCESS | 9744 | RSS: Main: 170852352 01:49:38 INFO - PROCESS | 9744 | 01:52:41 INFO - PROCESS | 9744 | Cycle 1(1): loaded http://localhost:42892/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42892/tests/dromaeo/cssquery-yui.html) 01:52:41 INFO - PROCESS | 9744 | RSS: Main: 173649920 01:52:41 INFO - PROCESS | 9744 | 01:55:44 INFO - PROCESS | 9744 | Cycle 1(1): loaded http://localhost:42892/tests/dromaeo/cssquery-yui.html (next: http://localhost:42892/tests/dromaeo/cssquery-dojo.html) 01:55:44 INFO - PROCESS | 9744 | RSS: Main: 267194368 01:55:44 INFO - PROCESS | 9744 | 01:55:44 INFO - PROCESS | 9744 | __start_tp_report 01:55:44 INFO - PROCESS | 9744 | _x_x_mozilla_page_load 01:55:44 INFO - PROCESS | 9744 | _x_x_mozilla_page_load_details 01:55:44 INFO - PROCESS | 9744 | |i|pagename|runs| 01:55:44 INFO - PROCESS | 9744 | |0;dojo.html;3364.10851124452;3403.978806358092;3404.7105995990332;3420.914477138073;3444.190615205426;5669.376368599456;5757.395473475282;5766.221560089387;5771.797987070453;5772.220750198732;5721.284839395075;5772.3361813391475;5780.306363236407;5781.62419442736;5796.30444346679;5141.845744627667;5188.974055129719;5197.116490196676;5198.532132108109;5234.319538459995;5009.12340340439;5211.244369566411;5233.633645644787;5245.501677340647;5282.709450980195;5052.418971818232;5075.340205773245;5126.92309615356;5128.692278463285;5141.794328226866;5179.689218646893;5204.401493828221;5205.765740541647;5206.843794686166;5208.817691380802;4959.404871415417;5061.063703214881;5126.769295381717;5168.302279192299;5175.870603234912;5592.552595792326;5600.0759874620635;5645.8023969161295;5663.915041274343;5667.263255776764;6099.054646529795;6138.570300078996;6138.84652883677;6150.04674275488;6152.784652537184;6194.783182588633;6218.409251121137;6218.751249950042;6222.191115155046;6231.968840155816;5565.053940830068;5594.216809646653;5735.942640573582;5747.971260143756;5779.479846813723;6320.462760665303;6321.525885558602;6321.841953951188;6322;6323.114763933053;6005.249343832021;6053.061775424816;6060.090986351995;6066.059760737093;6067.45392914631;5888.558358123072;5933.554983376264;6090.786822461281;6139.631622102688;6172.876542469216;6134.141220229171;6138;6138.570300079041;6141.9558675025355;6144.754209831557;5891.617044892068;5946.762129514771;5946.910796337972;6120.517928286853;6128.793191777182;4596.839110631112;4671.252599584051;4679.766011699401;4687.789049492781;4697.718136911796;4141.316682747313;4158.064435502049;4161.708680392343;4238.491381034296;4240.766757828287;5889.558283128783;5903.26209223847;5908.054711246266;5926.318473375617;5933.465988061095;6103.6032657877095;6114.205153330038;6134.386561343919;6138.478229350555;6144;5759.7408116634015;5791.681457519792;5927.081302398135;5935.703214839327;5940.554458415636;5202.193659982709;5212.687238765686;5246.029484545371;5298.841034768963;5302.125149350391;6460.353964603596;6460.386263305072;6466.030095485715;6466.094746735461;6472.417482426604;5850.23946886902;6173;6178.938210617836;6182.907256391248;6188.845278868064;6253.249610046823;6371.585846919936;6403.51973601991;6491.1236983008575;6522.836929076811;6097.268327800515;6104.969475152596;6108.389161083856;6109.877802444015;6112.602680825732;6206.534509911684;6228.190335256388;6231.22109736283;6231.626102433868;6238.906416403848;5688.7440065198;5791.131330300489;5872;5880.764769409176;5974;5878.147668587945;5940.376260492586;5940.79207227745;5942.821715348547;5970.522358211246;5780.104083867007;5946.108083787466;5946.524278057832;5947.494462970696;5950.077737950624;5874.236349274567;5921.082232254007;5972.790952316822;6026.0659597762415;6069.484093852072;5917.171595976481;5922.318933322551;5926.555508336978;5928.762849485972;5931.851703707442 01:55:44 INFO - PROCESS | 9744 | |1;ext.html;721.476929226311;780.9140994490605;795.0340336491162;866.2420382165609;875.5972252763728;32077.839610801944;32566.511502327456;35201.82399088004;35207;45232.547674523244;46004.079918401694;46578.30132548015;46600;46645.76677116618;46656.533434665645;9184.586693598787;9341.11259430353;9341.252699784001;9348.906510934872;9355.953220233889;7529.360004399605;7703.499272547267;9126.543672816366;9227.3079519036;10682.359058456479;11053.834192487118;11086.944565277163;11096.778064438748;11106;11112.722181945435;10989.230753847274;11005.944970275177;11016.17378696601;11023.1732620053;11030.944845275762;10783;10788.08301294391;10874.18443616732;10883.23817332787;10890.292131011538;43111.78444107791;43228.78385608083;43286.56713432857;43363.56636433627;43500;4118.320477121271;4159.251334759742;4164.167166566699;4263.232618128735;4298.075913678543;29847.403051939058;30112.69887301121;30365.848170759225;30439.391212175855;30718.385632287453;4315.180115778024;4436.201483732927;4441.644668426518;4473.686841921067;4521.434820647419;243893;244016;248997;249965;250461.7476912622;54911.45088549143;55184.44815551873;55617.44382556203;55671.164932526044;55757;57827;58076.70961645165;58097;58097.70951145217;58116;15864.206789660471;15889.920550397173;15891.125988070768;15901.761473577906;15964.521064368088;13824.585262442142;13826.792598111037;13834;13842.584722458343;13861.722765544633;11987;12323.198992065487;13666.18002919828;14121.717565648629;14311.57065288043;5672.914906276409;5682.687452190169;5726.398728133569;5756.884862302731;5832.941670583325;1028.4292217819182;1047.2512153810062;1047.5233768635253;1047.9528421221064;1057.7884423115408;28763.42473150567;28878.42243155125;28888.71111288902;28898.566521502195;29340.26649333741;1084.9728756781144;1101.5648818716722;1115.4311301236266;1117.7708569743183;1132.6432173865205;15002.02486838352;15311;15421.151836649093;15424.151671657943;15680.215989200495;291346;293736;294083;294588;294863;58579.12131318034;58723.41276587179;59007;59060.40939590549;59149;1532.9846701532842;1536.8386319436522;1557.128008315347;1581.533447632951;1581.8215429505092;26237.606435903857;27135.59296610513;27682.169534913985;27895.442091158468;28173.859130704215;42899.142017160106;42952;43023.56976430196;43025;43138.78430607827;278229;279855;280448;280529;281173;35068.64931350654;35946.8202658985;36009.279814404086;36035;36216.81891590525;36842.81578592197;37381.813090934374;41911;44352;44420.77789611031;34998.65001349954;35195.29609407849;35334;35401.82299088488;35660.821695891354;34931.30137397187;34933;35113.648863511036;35270;35280.11799705028;40279.19441611093;40458.39312410375;41121;41275.38086928638;41534;33346.4998025025;33369.666303337624;33928.491072634424;33936.490952635235;34000;26716.465670686088;26980;27320.590191147545;27381.178564642294;27410.862945685145 01:55:44 INFO - PROCESS | 9744 | |2;jquery.html;452.37572150432396;452.85508637236074;460.4405647138728;476.1310608140144;476.50443538719753;15087.471938482155;15628.609284767886;20053.59892802143;20459.386218413452;21002.26492072778;20705.17179312826;20962.685559716578;21652.88595166921;22171.113155473802;22363.217287394946;6813.965930170342;6973.232944376115;7004.194517630462;7009.579425234477;7018.438524917994;5190.844274671747;6706.530542862001;6833.487488438387;6885.380315771579;6928.030075789394;6919.65401729914;6919.688614012382;6920.342567456109;6924.822780127365;6927.480438967073;7076.32774886385;7320.304571065741;7352.264773522658;7356.043714317105;7357.3378395944355;7218.711251549932;7307.926920730778;7377.668004939791;7403.888941665826;7427.294407031323;24565.50868982628;24696.13563525286;24989.750102498925;25248.368790780194;25266.24201273946;3007.639083309995;3225.8387080645875;3273.018094571619;3492.4412094065083;3691.3724666806706;15479.380824767108;15954.202289885576;16065.518034458984;16092.27584758677;16102.114383708891;2213.8419922585326;2221.922232721863;2264.445210923318;2266.512699769541;2298.402415371999;77613.22386776116;79772.20227797765;79893.60053199755;94400.52799736026;115898.42050789692;17331.65336693259;17978.190981405867;18037.7294340585;18248.270069197348;18254.543636409195;31699;31849.52225716616;31872.362552748815;31984.680153198635;32274.677253227168;10035.548400322003;10048.949755251177;10058.949705251573;10148.391096534231;10268.794624107626;8296.543690096982;8363.414560980795;8384.958075209706;8537.402381833337;8558.101399353103;8946.284297256334;8996.775080622905;9080.81838363229;9153.77115572116;9352.345335826563;3760.7571431423708;3833.865814696528;3843.6925045996254;3875.7093218008763;3933.688679198336;958.8753462049889;961.4952150121103;968.7287559483356;969.3263182088381;981.1954197557934;19540.413787586396;20927.8953605231;20966.58066838655;20967.790322096585;20977.580448390945;986.225812736998;988.8763904511936;998.5107297424355;1010.2978429991085;1012.7215015870547;10791.892081079246;11174.217804753589;11203.887961120448;11285.153613479022;11800.940995294968;699841;701918;720905;722444;723523;115640;116753.41623291999;119732;122043.38978305052;124335;1275.2284867654935;1356.0846428660614;1357.3959587983252;1366.3851266929921;1367.0703921333588;16803.243854026354;18141.27434902642;18746.71879921775;18849.24603015919;18866.151023203714;51083;52005.7399712999;52076.739616301675;52432.21351679652;52798.47201527935;68165.65917170382;68803.65598171977;69305;70278.29721702718;71364.64317678378;10922.508487118257;11058.447077645924;11961.700957476132;11982.64052078439;12067.517299307929;11314.886851131383;12060.155789094679;12198.81701774492;12642.936785316015;13109;10508.894911050791;11226.887731122584;11391.031762299966;11488.19582629208;11489.942550287195;10476.057154855795;10583.470826458492;10740.409277489813;10790.1367890567;10956;11546.42267886619;11571.479283432098;11622.418879055844;11721.648350549498;11817.231879927776;12981.286029268482;13515.864841351853;13717.931410342884;13979;14066.367013484622;11532.308061516336;11902.761944761229;12084.697882553006;12122.090843186972;12302.938485307515 01:55:44 INFO - PROCESS | 9744 | |3;mootools.html;1507.9773803392943;1535.0866234590414;1563.7732528783326;1568.882333824963;1582.6122599963012;11014.118870490362;11780.410979451037;11784.941075294622;11786.528538858445;11793.7641247175;10972.670819875397;11225.550977960893;11232.887671123286;11240.100791936646;11538.480768365424;2404.362843846382;2437.2322718343753;2441.9755802441928;2443.059422122478;2444.4255599934;2346.636271377939;2385.7256415512265;2444.10790061628;2454.226918520669;2459.0409740201335;2360.0441821062495;2366.550355432471;2387.7254115776736;2444.168982545934;2445.1319781477587;2332.7667233276707;2368.988155059222;2374.8931298091543;2375;2446.4617784087473;2345.1205797825814;2348.236823032504;2357.5167090746527;2358.6697862299293;2359.3393849722106;11245.437728113644;11510;11517.36654484003;11518.654440366803;11529.077673786078;5698.7435565399655;5841.474267315962;5850.707464626794;5857.648541087506;5857.882842343172;3305.190228394059;3373.8650453981813;3412.658734126592;3421.2815308784934;3432.673895979878;1314.66476048607;1351.5607427586074;1358.8844948179417;1385.3973521518171;1399.4052527675697;15070.09579425238;15487.457938972195;16154.353825847069;17533;17732.46802595911;4533.818647254139;4599.379083823641;4715.127701375257;4717.976410117927;4746.1931471649905;10938.56245750177;11207.103431725442;12157.209781364183;13080.411381487856;13120.409581568852;5792.391798861059;5888.146218798251;5902.409759024149;5903.498202652737;5904.1734157218025;5405.324334458192;5436.374816896107;5472.699001554873;5477.20580515823;5499.670019798825;3898.2983063048355;4005.6581045349553;4035.636792688672;4050.250703619781;4062.73592216505;994.0506815990711;995.2734503812258;1003.82934901067;1009.0968583118068;1012.0335079998583;855.6449073634373;889.7197382824389;891.4250308550949;897.9146981036791;919.1773362840221;7041.295870413021;7131.429485641136;7133.964330178316;7140.892886606705;7222.349988501061;1134.6993046842592;1140.589387820384;1146.5643055638805;1153.1581945179898;1153.7231064544449;4607.861764147081;4723.385959825201;4727.952720472751;4787.760611969387;4798.424189097261;14223.857761422254;14885.957982941307;14913.92543037278;14917.328720207617;14930.701385972437;14150.716985660229;14343.713125737428;14521.636959075897;14565.41738330455;14578.416863325348;10421.843672344921;10722.41026743521;10754.247202695893;10806.621768238074;10826.296290741077;7509.7371591994015;7574.772756817304;7721.845563088819;7742.4193185510185;7792.727254546064;12816.295103769382;13049.282289473791;13091.149075310075;13121.803172952223;13206.867931320949;11656.358900260568;13514.121582097132;13517.121387109806;13537;13672.794908076587;8734.213920747163;9511.429314241168;9694.709158725249;9882.604695812088;9983.850242246517;11016.06363459115;11038.227324087558;11118.110551156049;11121.944390277997;11182.944085279521;9289.396189247676;9306.860397093913;9338.066193380879;9355.298352623444;9493.193078588121;9128.406653567497;9203.953980230324;9219.44683319003;9354.064593540592;9366.203872670627;8232.053313868983;8250.587470626564;8286.875696864432;8363.665453381796;8425.62084706202;6115.510759139169;6232.376762323913;6234.594751341329;6401.199850018747;6421.71102300407;6267.373262673696;6425.742970281323;6522.5760325578685;6655.267920528641;6699.598024118569 01:55:44 INFO - PROCESS | 9744 | |4;prototype.html;423.73093085890457;424.5499770243541;425.0372905369337;427.25444100045405;429.7378599054574;5280.445553216915;5317.760700768465;5341.385740639827;5351.866203344918;5361.705106219161;4845.733484658343;5015.899682006367;5086.720230387327;5165.457626949173;5213.009528189641;4360.869173924787;4363.280058790296;4390.604845563898;4418.64650827933;4423.557644235567;4412.859763014952;4415.580519850625;4434.157510073076;4440.400545926307;4482.327650852382;4276.572342765729;4280.293751530994;4308.9353659695125;4453.6659750518675;4460.576245256695;4419.779011049467;4431.559743083479;4431.911361772746;4457.777111144463;4468.50846406895;4264.360345948133;4266.8052945175405;4280.700350975433;4283.87148385549;4289.970407102304;5013.423456302536;5039.790450291904;5075.492450754932;5151.381834179922;5192.247124167013;5176.844694659167;5213.296205012312;5233.581313494911;5270.919461510382;5281.141349958048;5529.4470552944385;5535.972320138414;5536.764375252935;5551.306086739158;5584.441555844423;4454.621357184643;4591.173588754023;4625.236835922069;4813.302071199691;4838.927416088762;5647.604667673223;5729.762684083479;5735.483806457397;5736.311642602915;5751.338596061465;4738.763061846963;4749.572538471554;4765.213739732905;4772.308015337757;4810;4681.133990211822;4707.870111173363;4789.880252993703;4824.201962861532;4869.610431165569;5042.243663450512;5067.315912351785;5071.554606937005;5135.974320128375;5179.741012949337;5507.862303442366;5515.834524964257;5545.753969642221;5608.802982300132;5616.438356164351;1607.686501132268;1644.2817255967543;1661.7258152404959;1671.3620651761341;1682.1168886334772;1183.207251141737;1207.4506099724604;1252.6680429686141;1273.802560603108;1309.685675437888;5405.459454054563;5428.4028756836715;5447.754851031713;5479.945200548023;5498.670079795225;5520.199571062173;5613.354464236665;5651.689157096317;5653.491185793216;5667;5131.589472842238;5414.458554144554;5433.375161856437;5457.972710136424;5473.2063850742215;2963.7628989680775;2978.5532170174647;3001.5047517159423;3008.9548656770166;3017.5473678948333;5576.553875689935;5744.856378590485;5818.796342128006;5820.796272130455;5845.473907348359;5773.971130144322;5774.711264436762;5783.872144931782;5796.88406231873;5808.912866307009;5934.406559344117;5941.287045554561;5950.2264705587995;5995.970020149872;6010.819675409744;5428.918566221589;5604.635698679573;5627.21219029328;5671.6597004179885;5756.71216439168;5596.608237423341;5607.831765047055;5625.46558076982;5641.26663533738;5647.604667673388;5627.396192085236;5879.441453061952;5916.852078698067;5917.644941303535;5949.315828679757;4707.17624415719;4737.502562231054;4738.739369334582;4764.666473346832;4778.354922085474;5713.457221563945;5737.971310143423;5740.827775166752;5765.336986246635;5772;5423.156126916867;5546.142042415836;5564.387917329172;5578.888422231614;5586.190002449704;5503.504684578407;5526.226328313959;5526.618345413646;5536.335639723259;5565.415631358649;5351.892962140658;5453.481919217668;5527;5558.749856256559;5583.832485025456;5460.945390546202;5465.59008074388;5486.780528778804;5493.807716729895;5502.779888804403;5415.431379705232;5438.809641662523;5446.809361672323;5448.210009548672;5470.398256191896 01:55:44 INFO - PROCESS | 9744 | |5;yui.html;842.2798507276275;858.5792961448892;897.3673560140102;903.9005709371968;913.2191975860638;15324.69350612987;16078.758818617716;16641;16701.83298167018;16744.246508907097;14691.55925322242;14751.041182323168;14853.108813471199;15235.162066086361;15344.539663810103;857.5069335132308;871.2289623683055;873.2271939333672;873.2620935309653;873.5239294584467;854.9572521373938;871.9738407847775;885.8538341173698;885.9158379953926;886.9068747781455;934.5420743835506;936.2463217110258;936.3585943628602;936.4802534593298;937.643695395752;925.3799954030816;926.578406824893;927.7216834949522;928.3547934185743;928.4475736936512;684.384054351083;685.3044160177424;685.3763075601179;685.4482141875789;685.5852209413333;16164.272607732562;16497.505074847773;16550.255238514295;16873.325066997306;17057.82942170575;15085.773713394308;16086.75869861953;16123.355065797472;16380.26288817006;16624.25190866414;12871.543549286818;13009.739805203937;13286.26925519096;13384.263865487394;13496.93251533746;3917.7453465524936;3925.941110883339;3933.567307596162;3982.3628219484744;4058.370952502367;88259;88908;89052;89348.63908165596;90666.5466672669;12407.093460871165;12650.150336704557;12815.807762883564;13103.475860965455;13197.802032969514;24014.15950441726;25345.493090138094;25380.87309563477;25724.356891077874;25747.227583172535;8846.380753347332;8923.15230053144;9189.862152067724;9286.888981354849;9438.952805236067;5127.282180494659;5238.345206849144;5281.049411106038;5317.7341132943975;5359.330083739533;3554.431290993429;3607.1703508193264;3622.3660859350034;3644.6173151818675;3657.926841463156;590.6279044202211;591.4588151841017;597.9222701048836;600.1717629671114;605.1286147946943;729.7701224114387;734.0897287363738;748.655618415524;753.2655660730744;779.1351599724302;9869.457179855177;10181.745456363502;10368.170546356274;10962.835557466644;10983.505742241618;699.720111955222;711.9679614417364;729.0631538473036;734.2253922112117;745.3552676934413;4966.255061740696;5026.321446604735;5042.773075211624;5062.443131255559;5064.290999260106;66261.66869165719;80595;81548;82955;85164;66253.33746662569;67164.3283567158;67461;67486;68550;61513;63676.68161659162;64594;64708.352916470234;65452.67273663697;11415.828762568734;12051.216670916363;12082.939585302018;12362.3818809061;12462.813057803958;37922;38338.6166138335;38506.80746596249;43945.560544394146;44644.33033504435;55559.44440555543;56505.86988260294;57254.7137264311;57986.42013579979;60018;12674.936625317183;12804.48782048745;12935;13044.673883152996;13065.608031759062;14941.626459338604;15125.243737812845;15208.695826083194;15312.846871531588;15556.455524056604;15703.371865125268;15933.20333983273;15944.840551594336;16125.677486449971;16181.271842767339;14264.144151350953;14649.340779664728;14872.776908346166;15075.170865602497;15554.144522051396;14897;14932.925335373253;15098;15255.466058687893;15330.846691532943;10772;10947.781044378908;11007.889921101007;11287.7742445149;11359.659210223706;12539.561115360919;12746.235225886476;12940.741185176432;12991;13286.80069798973 01:55:44 INFO - PROCESS | 9744 | __end_tp_report 01:55:44 INFO - PROCESS | 9744 | __start_cc_report 01:55:44 INFO - PROCESS | 9744 | _x_x_mozilla_cycle_collect,1435 01:55:44 INFO - PROCESS | 9744 | __end_cc_report 01:55:44 INFO - PROCESS | 9744 | __startTimestamp1491468944280__endTimestamp 01:55:44 INFO - PROCESS | 9744 | 01:55:44 INFO - PROCESS | 9744 | ------- Summary: start ------- 01:55:44 INFO - PROCESS | 9744 | Number of tests: 6 01:55:44 INFO - PROCESS | 9744 | 01:55:44 INFO - PROCESS | 9744 | [#0] dojo.html Cycles:165 Average:5693.36 Median:5922.32 stddev:652.98 (11.0%) stddev-sans-first:628.90 01:55:44 INFO - PROCESS | 9744 | Values: 3364.1 3404.0 3404.7 3420.9 3444.2 5669.4 5757.4 5766.2 5771.8 5772.2 5721.3 5772.3 5780.3 5781.6 5796.3 5141.8 5189.0 5197.1 5198.5 5234.3 5009.1 5211.2 5233.6 5245.5 5282.7 5052.4 5075.3 5126.9 5128.7 5141.8 5179.7 5204.4 5205.8 5206.8 5208.8 4959.4 5061.1 5126.8 5168.3 5175.9 5592.6 5600.1 5645.8 5663.9 5667.3 6099.1 6138.6 6138.8 6150.0 6152.8 6194.8 6218.4 6218.8 6222.2 6232.0 5565.1 5594.2 5735.9 5748.0 5779.5 6320.5 6321.5 6321.8 6322.0 6323.1 6005.2 6053.1 6060.1 6066.1 6067.5 5888.6 5933.6 6090.8 6139.6 6172.9 6134.1 6138.0 6138.6 6142.0 6144.8 5891.6 5946.8 5946.9 6120.5 6128.8 4596.8 4671.3 4679.8 4687.8 4697.7 4141.3 4158.1 4161.7 4238.5 4240.8 5889.6 5903.3 5908.1 5926.3 5933.5 6103.6 6114.2 6134.4 6138.5 6144.0 5759.7 5791.7 5927.1 5935.7 5940.6 5202.2 5212.7 5246.0 5298.8 5302.1 6460.4 6460.4 6466.0 6466.1 6472.4 5850.2 6173.0 6178.9 6182.9 6188.8 6253.2 6371.6 6403.5 6491.1 6522.8 6097.3 6105.0 6108.4 6109.9 6112.6 6206.5 6228.2 6231.2 6231.6 6238.9 5688.7 5791.1 5872.0 5880.8 5974.0 5878.1 5940.4 5940.8 5942.8 5970.5 5780.1 5946.1 5946.5 5947.5 5950.1 5874.2 5921.1 5972.8 6026.1 6069.5 5917.2 5922.3 5926.6 5928.8 5931.9 01:55:44 INFO - PROCESS | 9744 | 01:55:44 INFO - PROCESS | 9744 | [#1] ext.html Cycles:180 Average:45183.90 Median:33932.49 stddev:71408.59 (210.4%) stddev-sans-first:71530.87 01:55:44 INFO - PROCESS | 9744 | Values: 721.5 780.9 795.0 866.2 875.6 32077.8 32566.5 35201.8 35207.0 45232.5 46004.1 46578.3 46600.0 46645.8 46656.5 9184.6 9341.1 9341.3 9348.9 9356.0 7529.4 7703.5 9126.5 9227.3 10682.4 11053.8 11086.9 11096.8 11106.0 11112.7 10989.2 11005.9 11016.2 11023.2 11030.9 10783.0 10788.1 10874.2 10883.2 10890.3 43111.8 43228.8 43286.6 43363.6 43500.0 4118.3 4159.3 4164.2 4263.2 4298.1 29847.4 30112.7 30365.8 30439.4 30718.4 4315.2 4436.2 4441.6 4473.7 4521.4 243893.0 244016.0 248997.0 249965.0 250461.7 54911.5 55184.4 55617.4 55671.2 55757.0 57827.0 58076.7 58097.0 58097.7 58116.0 15864.2 15889.9 15891.1 15901.8 15964.5 13824.6 13826.8 13834.0 13842.6 13861.7 11987.0 12323.2 13666.2 14121.7 14311.6 5672.9 5682.7 5726.4 5756.9 5832.9 1028.4 1047.3 1047.5 1048.0 1057.8 28763.4 28878.4 28888.7 28898.6 29340.3 1085.0 1101.6 1115.4 1117.8 1132.6 15002.0 15311.0 15421.2 15424.2 15680.2 291346.0 293736.0 294083.0 294588.0 294863.0 58579.1 58723.4 59007.0 59060.4 59149.0 1533.0 1536.8 1557.1 1581.5 1581.8 26237.6 27135.6 27682.2 27895.4 28173.9 42899.1 42952.0 43023.6 43025.0 43138.8 278229.0 279855.0 280448.0 280529.0 281173.0 35068.6 35946.8 36009.3 36035.0 36216.8 36842.8 37381.8 41911.0 44352.0 44420.8 34998.7 35195.3 35334.0 35401.8 35660.8 34931.3 34933.0 35113.6 35270.0 35280.1 40279.2 40458.4 41121.0 41275.4 41534.0 33346.5 33369.7 33928.5 33936.5 34000.0 26716.5 26980.0 27320.6 27381.2 27410.9 01:55:44 INFO - PROCESS | 9744 | 01:55:44 INFO - PROCESS | 9744 | [#2] jquery.html Cycles:180 Average:38461.58 Median:2265.48 stddev:117210.46 (5173.8%) stddev-sans-first:117504.52 01:55:44 INFO - PROCESS | 9744 | Values: 452.4 452.9 460.4 476.1 476.5 15087.5 15628.6 20053.6 20459.4 21002.3 20705.2 20962.7 21652.9 22171.1 22363.2 6814.0 6973.2 7004.2 7009.6 7018.4 5190.8 6706.5 6833.5 6885.4 6928.0 6919.7 6919.7 6920.3 6924.8 6927.5 7076.3 7320.3 7352.3 7356.0 7357.3 7218.7 7307.9 7377.7 7403.9 7427.3 24565.5 24696.1 24989.8 25248.4 25266.2 3007.6 3225.8 3273.0 3492.4 3691.4 15479.4 15954.2 16065.5 16092.3 16102.1 2213.8 2221.9 2264.4 2266.5 2298.4 77613.2 79772.2 79893.6 94400.5 115898.4 17331.7 17978.2 18037.7 18248.3 18254.5 31699.0 31849.5 31872.4 31984.7 32274.7 10035.5 10048.9 10058.9 10148.4 10268.8 8296.5 8363.4 8385.0 8537.4 8558.1 8946.3 8996.8 9080.8 9153.8 9352.3 3760.8 3833.9 3843.7 3875.7 3933.7 958.9 961.5 968.7 969.3 981.2 19540.4 20927.9 20966.6 20967.8 20977.6 986.2 988.9 998.5 1010.3 1012.7 10791.9 11174.2 11203.9 11285.2 11800.9 699841.0 701918.0 720905.0 722444.0 723523.0 115640.0 116753.4 119732.0 122043.4 124335.0 1275.2 1356.1 1357.4 1366.4 1367.1 16803.2 18141.3 18746.7 18849.2 18866.2 51083.0 52005.7 52076.7 52432.2 52798.5 68165.7 68803.7 69305.0 70278.3 71364.6 10922.5 11058.4 11961.7 11982.6 12067.5 11314.9 12060.2 12198.8 12642.9 13109.0 10508.9 11226.9 11391.0 11488.2 11489.9 10476.1 10583.5 10740.4 10790.1 10956.0 11546.4 11571.5 11622.4 11721.6 11817.2 12981.3 13515.9 13717.9 13979.0 14066.4 11532.3 11902.8 12084.7 12122.1 12302.9 01:55:44 INFO - PROCESS | 9744 | 01:55:44 INFO - PROCESS | 9744 | [#3] mootools.html Cycles:180 Average:7112.46 Median:2443.58 stddev:4516.06 (184.8%) stddev-sans-first:4509.09 01:55:44 INFO - PROCESS | 9744 | Values: 1508.0 1535.1 1563.8 1568.9 1582.6 11014.1 11780.4 11784.9 11786.5 11793.8 10972.7 11225.6 11232.9 11240.1 11538.5 2404.4 2437.2 2442.0 2443.1 2444.4 2346.6 2385.7 2444.1 2454.2 2459.0 2360.0 2366.6 2387.7 2444.2 2445.1 2332.8 2369.0 2374.9 2375.0 2446.5 2345.1 2348.2 2357.5 2358.7 2359.3 11245.4 11510.0 11517.4 11518.7 11529.1 5698.7 5841.5 5850.7 5857.6 5857.9 3305.2 3373.9 3412.7 3421.3 3432.7 1314.7 1351.6 1358.9 1385.4 1399.4 15070.1 15487.5 16154.4 17533.0 17732.5 4533.8 4599.4 4715.1 4718.0 4746.2 10938.6 11207.1 12157.2 13080.4 13120.4 5792.4 5888.1 5902.4 5903.5 5904.2 5405.3 5436.4 5472.7 5477.2 5499.7 3898.3 4005.7 4035.6 4050.3 4062.7 994.1 995.3 1003.8 1009.1 1012.0 855.6 889.7 891.4 897.9 919.2 7041.3 7131.4 7134.0 7140.9 7222.3 1134.7 1140.6 1146.6 1153.2 1153.7 4607.9 4723.4 4728.0 4787.8 4798.4 14223.9 14886.0 14913.9 14917.3 14930.7 14150.7 14343.7 14521.6 14565.4 14578.4 10421.8 10722.4 10754.2 10806.6 10826.3 7509.7 7574.8 7721.8 7742.4 7792.7 12816.3 13049.3 13091.1 13121.8 13206.9 11656.4 13514.1 13517.1 13537.0 13672.8 8734.2 9511.4 9694.7 9882.6 9983.9 11016.1 11038.2 11118.1 11121.9 11182.9 9289.4 9306.9 9338.1 9355.3 9493.2 9128.4 9204.0 9219.4 9354.1 9366.2 8232.1 8250.6 8286.9 8363.7 8425.6 6115.5 6232.4 6234.6 6401.2 6421.7 6267.4 6425.7 6522.6 6655.3 6699.6 01:55:44 INFO - PROCESS | 9744 | 01:55:44 INFO - PROCESS | 9744 | [#4] prototype.html Cycles:180 Average:4837.07 Median:5356.80 stddev:1285.31 (24.0%) stddev-sans-first:1245.50 01:55:44 INFO - PROCESS | 9744 | Values: 423.7 424.5 425.0 427.3 429.7 5280.4 5317.8 5341.4 5351.9 5361.7 4845.7 5015.9 5086.7 5165.5 5213.0 4360.9 4363.3 4390.6 4418.6 4423.6 4412.9 4415.6 4434.2 4440.4 4482.3 4276.6 4280.3 4308.9 4453.7 4460.6 4419.8 4431.6 4431.9 4457.8 4468.5 4264.4 4266.8 4280.7 4283.9 4290.0 5013.4 5039.8 5075.5 5151.4 5192.2 5176.8 5213.3 5233.6 5270.9 5281.1 5529.4 5536.0 5536.8 5551.3 5584.4 4454.6 4591.2 4625.2 4813.3 4838.9 5647.6 5729.8 5735.5 5736.3 5751.3 4738.8 4749.6 4765.2 4772.3 4810.0 4681.1 4707.9 4789.9 4824.2 4869.6 5042.2 5067.3 5071.6 5136.0 5179.7 5507.9 5515.8 5545.8 5608.8 5616.4 1607.7 1644.3 1661.7 1671.4 1682.1 1183.2 1207.5 1252.7 1273.8 1309.7 5405.5 5428.4 5447.8 5479.9 5498.7 5520.2 5613.4 5651.7 5653.5 5667.0 5131.6 5414.5 5433.4 5458.0 5473.2 2963.8 2978.6 3001.5 3009.0 3017.5 5576.6 5744.9 5818.8 5820.8 5845.5 5774.0 5774.7 5783.9 5796.9 5808.9 5934.4 5941.3 5950.2 5996.0 6010.8 5428.9 5604.6 5627.2 5671.7 5756.7 5596.6 5607.8 5625.5 5641.3 5647.6 5627.4 5879.4 5916.9 5917.6 5949.3 4707.2 4737.5 4738.7 4764.7 4778.4 5713.5 5738.0 5740.8 5765.3 5772.0 5423.2 5546.1 5564.4 5578.9 5586.2 5503.5 5526.2 5526.6 5536.3 5565.4 5351.9 5453.5 5527.0 5558.7 5583.8 5460.9 5465.6 5486.8 5493.8 5502.8 5415.4 5438.8 5446.8 5448.2 5470.4 01:55:44 INFO - PROCESS | 9744 | 01:55:44 INFO - PROCESS | 9744 | [#5] yui.html Cycles:180 Average:18560.95 Median:4020.37 stddev:23233.33 (577.9%) stddev-sans-first:23260.41 01:55:44 INFO - PROCESS | 9744 | Values: 842.3 858.6 897.4 903.9 913.2 15324.7 16078.8 16641.0 16701.8 16744.2 14691.6 14751.0 14853.1 15235.2 15344.5 857.5 871.2 873.2 873.3 873.5 855.0 872.0 885.9 885.9 886.9 934.5 936.2 936.4 936.5 937.6 925.4 926.6 927.7 928.4 928.4 684.4 685.3 685.4 685.4 685.6 16164.3 16497.5 16550.3 16873.3 17057.8 15085.8 16086.8 16123.4 16380.3 16624.3 12871.5 13009.7 13286.3 13384.3 13496.9 3917.7 3925.9 3933.6 3982.4 4058.4 88259.0 88908.0 89052.0 89348.6 90666.5 12407.1 12650.2 12815.8 13103.5 13197.8 24014.2 25345.5 25380.9 25724.4 25747.2 8846.4 8923.2 9189.9 9286.9 9439.0 5127.3 5238.3 5281.0 5317.7 5359.3 3554.4 3607.2 3622.4 3644.6 3657.9 590.6 591.5 597.9 600.2 605.1 729.8 734.1 748.7 753.3 779.1 9869.5 10181.7 10368.2 10962.8 10983.5 699.7 712.0 729.1 734.2 745.4 4966.3 5026.3 5042.8 5062.4 5064.3 66261.7 80595.0 81548.0 82955.0 85164.0 66253.3 67164.3 67461.0 67486.0 68550.0 61513.0 63676.7 64594.0 64708.4 65452.7 11415.8 12051.2 12082.9 12362.4 12462.8 37922.0 38338.6 38506.8 43945.6 44644.3 55559.4 56505.9 57254.7 57986.4 60018.0 12674.9 12804.5 12935.0 13044.7 13065.6 14941.6 15125.2 15208.7 15312.8 15556.5 15703.4 15933.2 15944.8 16125.7 16181.3 14264.1 14649.3 14872.8 15075.2 15554.1 14897.0 14932.9 15098.0 15255.5 15330.8 10772.0 10947.8 11007.9 11287.8 11359.7 12539.6 12746.2 12940.7 12991.0 13286.8 01:55:44 INFO - PROCESS | 9744 | -------- Summary: end -------- 01:55:44 INFO - PROCESS | 9744 | 01:55:45 INFO - TEST-INFO | 9744: exit 0 01:55:45 INFO - TEST-OK | dromaeo_css | took 1115853ms 01:55:45 INFO - TEST-START | kraken 01:55:45 INFO - operating with platform_type : linux_ 01:55:45 INFO - Initialising browser for kraken test... 01:55:45 INFO - TEST-INFO | started process 10066 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdkValG/profile http://localhost:42892/getInfo.html) 01:55:49 INFO - PROCESS | 10066 | __metrics Screen width/height:1600/1200 01:55:49 INFO - PROCESS | 10066 | colorDepth:24 01:55:49 INFO - PROCESS | 10066 | Browser inner width/height: 1024/697 01:55:49 INFO - PROCESS | 10066 | __metrics 01:55:50 INFO - PROCESS | 10066 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:55:50 INFO - PROCESS | 10066 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:55:51 INFO - TEST-INFO | 10066: exit 0 01:55:51 INFO - Browser initialized. 01:55:51 INFO - Running cycle 1/1 for kraken test... 01:55:51 INFO - TEST-INFO | started process 10164 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdkValG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:56:03 INFO - PROCESS | 10164 | RSS: Main: 139845632 01:56:03 INFO - PROCESS | 10164 | 01:56:05 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42892/tests/kraken/driver.html?testName=audio-beat-detection) 01:56:06 INFO - PROCESS | 10164 | RSS: Main: 184016896 01:56:06 INFO - PROCESS | 10164 | 01:56:09 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42892/tests/kraken/driver.html?testName=audio-dft) 01:56:09 INFO - PROCESS | 10164 | RSS: Main: 173916160 01:56:09 INFO - PROCESS | 10164 | 01:56:12 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42892/tests/kraken/driver.html?testName=audio-fft) 01:56:12 INFO - PROCESS | 10164 | RSS: Main: 265289728 01:56:12 INFO - PROCESS | 10164 | 01:56:15 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42892/tests/kraken/driver.html?testName=audio-oscillator) 01:56:15 INFO - PROCESS | 10164 | RSS: Main: 171634688 01:56:15 INFO - PROCESS | 10164 | 01:56:17 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42892/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:56:18 INFO - PROCESS | 10164 | RSS: Main: 174387200 01:56:18 INFO - PROCESS | 10164 | 01:56:21 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42892/tests/kraken/driver.html?testName=imaging-darkroom) 01:56:21 INFO - PROCESS | 10164 | RSS: Main: 214056960 01:56:21 INFO - PROCESS | 10164 | 01:56:25 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42892/tests/kraken/driver.html?testName=imaging-desaturate) 01:56:25 INFO - PROCESS | 10164 | RSS: Main: 212344832 01:56:25 INFO - PROCESS | 10164 | 01:56:28 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42892/tests/kraken/driver.html?testName=json-parse-financial) 01:56:29 INFO - PROCESS | 10164 | RSS: Main: 210305024 01:56:29 INFO - PROCESS | 10164 | 01:56:30 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42892/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:56:31 INFO - PROCESS | 10164 | RSS: Main: 172945408 01:56:31 INFO - PROCESS | 10164 | 01:56:33 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42892/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:56:33 INFO - PROCESS | 10164 | RSS: Main: 179589120 01:56:33 INFO - PROCESS | 10164 | 01:56:35 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42892/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:56:36 INFO - PROCESS | 10164 | RSS: Main: 189874176 01:56:36 INFO - PROCESS | 10164 | 01:56:38 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:56:39 INFO - PROCESS | 10164 | RSS: Main: 198545408 01:56:39 INFO - PROCESS | 10164 | 01:56:42 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:56:42 INFO - PROCESS | 10164 | RSS: Main: 195637248 01:56:42 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | Cycle 1(1): loaded http://localhost:42892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42892/tests/kraken/driver.html?testName=ai-astar) 01:56:44 INFO - PROCESS | 10164 | RSS: Main: 245694464 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | __start_tp_report 01:56:44 INFO - PROCESS | 10164 | _x_x_mozilla_page_load 01:56:44 INFO - PROCESS | 10164 | _x_x_mozilla_page_load_details 01:56:44 INFO - PROCESS | 10164 | |i|pagename|runs| 01:56:44 INFO - PROCESS | 10164 | |0;ai-astar;108;113;114;114;115;114;113;112;111;113 01:56:44 INFO - PROCESS | 10164 | |1;audio-beat-detection;137;115;115;151;117;123;116;115;131;115 01:56:44 INFO - PROCESS | 10164 | |2;audio-dft;163;161;162;163;160;163;161;160;161;160 01:56:44 INFO - PROCESS | 10164 | |3;audio-fft;76;76;75;115;76;76;76;76;89;76 01:56:44 INFO - PROCESS | 10164 | |4;audio-oscillator;81;83;101;83;83;83;94;84;82;93 01:56:44 INFO - PROCESS | 10164 | |5;imaging-gaussian-blur;113;114;113;113;114;113;113;113;113;113 01:56:44 INFO - PROCESS | 10164 | |6;imaging-darkroom;146;146;146;146;147;147;147;147;147;146 01:56:44 INFO - PROCESS | 10164 | |7;imaging-desaturate;95;94;94;94;93;93;94;94;94;93 01:56:44 INFO - PROCESS | 10164 | |8;json-parse-financial;55;53;55;53;55;55;54;55;54;55 01:56:44 INFO - PROCESS | 10164 | |9;json-stringify-tinderbox;54;54;54;54;53;53;54;54;53;54 01:56:44 INFO - PROCESS | 10164 | |10;stanford-crypto-aes;75;79;86;76;78;79;81;85;76;85 01:56:44 INFO - PROCESS | 10164 | |11;stanford-crypto-ccm;134;120;128;121;133;134;119;119;160;120 01:56:44 INFO - PROCESS | 10164 | |12;stanford-crypto-pbkdf2;164;166;161;160;162;161;163;160;159;158 01:56:44 INFO - PROCESS | 10164 | |13;stanford-crypto-sha256-iterative;60;60;59;59;60;60;65;59;59;59 01:56:44 INFO - PROCESS | 10164 | __end_tp_report 01:56:44 INFO - PROCESS | 10164 | __start_cc_report 01:56:44 INFO - PROCESS | 10164 | _x_x_mozilla_cycle_collect,1687 01:56:44 INFO - PROCESS | 10164 | __end_cc_report 01:56:44 INFO - PROCESS | 10164 | __startTimestamp1491469004681__endTimestamp 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | ------- Summary: start ------- 01:56:44 INFO - PROCESS | 10164 | Number of tests: 14 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.50 stddev:2.00 (1.8%) stddev-sans-first:1.20 01:56:44 INFO - PROCESS | 10164 | Values: 108.0 113.0 114.0 114.0 115.0 114.0 113.0 112.0 111.0 113.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:120.00 stddev:12.39 (10.3%) stddev-sans-first:12.14 01:56:44 INFO - PROCESS | 10164 | Values: 137.0 115.0 115.0 151.0 117.0 123.0 116.0 115.0 131.0 115.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.26 (0.8%) stddev-sans-first:1.20 01:56:44 INFO - PROCESS | 10164 | Values: 163.0 161.0 162.0 163.0 160.0 163.0 161.0 160.0 161.0 160.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#3] audio-fft Cycles:10 Average:81.10 Median:76.00 stddev:12.61 (16.6%) stddev-sans-first:13.24 01:56:44 INFO - PROCESS | 10164 | Values: 76.0 76.0 75.0 115.0 76.0 76.0 76.0 76.0 89.0 76.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:83.00 stddev:6.78 (8.2%) stddev-sans-first:6.87 01:56:44 INFO - PROCESS | 10164 | Values: 81.0 83.0 101.0 83.0 83.0 83.0 94.0 84.0 82.0 93.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#5] imaging-gaussian-blur Cycles:10 Average:113.20 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 01:56:44 INFO - PROCESS | 10164 | Values: 113.0 114.0 113.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 01:56:44 INFO - PROCESS | 10164 | Values: 146.0 146.0 146.0 146.0 147.0 147.0 147.0 147.0 147.0 146.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 01:56:44 INFO - PROCESS | 10164 | Values: 95.0 94.0 94.0 94.0 93.0 93.0 94.0 94.0 94.0 93.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:55.00 stddev:0.84 (1.5%) stddev-sans-first:0.87 01:56:44 INFO - PROCESS | 10164 | Values: 55.0 53.0 55.0 53.0 55.0 55.0 54.0 55.0 54.0 55.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#9] json-stringify-tinderbox Cycles:10 Average:53.70 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 01:56:44 INFO - PROCESS | 10164 | Values: 54.0 54.0 54.0 54.0 53.0 53.0 54.0 54.0 53.0 54.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.00 stddev:4.08 (5.1%) stddev-sans-first:3.91 01:56:44 INFO - PROCESS | 10164 | Values: 75.0 79.0 86.0 76.0 78.0 79.0 81.0 85.0 76.0 85.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:130.50 stddev:12.71 (9.7%) stddev-sans-first:13.34 01:56:44 INFO - PROCESS | 10164 | Values: 134.0 120.0 128.0 121.0 133.0 134.0 119.0 119.0 160.0 120.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.40 Median:161.50 stddev:2.41 (1.5%) stddev-sans-first:2.37 01:56:44 INFO - PROCESS | 10164 | Values: 164.0 166.0 161.0 160.0 162.0 161.0 163.0 160.0 159.0 158.0 01:56:44 INFO - PROCESS | 10164 | 01:56:44 INFO - PROCESS | 10164 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.00 Median:60.00 stddev:1.83 (3.0%) stddev-sans-first:1.94 01:56:44 INFO - PROCESS | 10164 | Values: 60.0 60.0 59.0 59.0 60.0 60.0 65.0 59.0 59.0 59.0 01:56:44 INFO - PROCESS | 10164 | -------- Summary: end -------- 01:56:44 INFO - PROCESS | 10164 | 01:56:45 INFO - TEST-INFO | 10164: exit 0 01:56:45 INFO - TEST-OK | kraken | took 60717ms 01:56:45 INFO - SUITE-END | took 1176s 01:56:45 INFO - Completed test suite (00:19:36) 01:56:45 INFO - javascript benchmark 01:56:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7816.469099315853, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3364.10851124452, 3403.978806358092, 3404.7105995990332, 3420.914477138073, 3444.190615205426, 5669.376368599456, 5757.395473475282, 5766.221560089387, 5771.797987070453, 5772.220750198732, 5721.284839395075, 5772.3361813391475, 5780.306363236407, 5781.62419442736, 5796.30444346679, 5141.845744627667, 5188.974055129719, 5197.116490196676, 5198.532132108109, 5234.319538459995, 5009.12340340439, 5211.244369566411, 5233.633645644787, 5245.501677340647, 5282.709450980195, 5052.418971818232, 5075.340205773245, 5126.92309615356, 5128.692278463285, 5141.794328226866, 5179.689218646893, 5204.401493828221, 5205.765740541647, 5206.843794686166, 5208.817691380802, 4959.404871415417, 5061.063703214881, 5126.769295381717, 5168.302279192299, 5175.870603234912, 5592.552595792326, 5600.0759874620635, 5645.8023969161295, 5663.915041274343, 5667.263255776764, 6099.054646529795, 6138.570300078996, 6138.84652883677, 6150.04674275488, 6152.784652537184, 6194.783182588633, 6218.409251121137, 6218.751249950042, 6222.191115155046, 6231.968840155816, 5565.053940830068, 5594.216809646653, 5735.942640573582, 5747.971260143756, 5779.479846813723, 6320.462760665303, 6321.525885558602, 6321.841953951188, 6322.0, 6323.114763933053, 6005.249343832021, 6053.061775424816, 6060.090986351995, 6066.059760737093, 6067.45392914631, 5888.558358123072, 5933.554983376264, 6090.786822461281, 6139.631622102688, 6172.876542469216, 6134.141220229171, 6138.0, 6138.570300079041, 6141.9558675025355, 6144.754209831557, 5891.617044892068, 5946.762129514771, 5946.910796337972, 6120.517928286853, 6128.793191777182, 4596.839110631112, 4671.252599584051, 4679.766011699401, 4687.789049492781, 4697.718136911796, 4141.316682747313, 4158.064435502049, 4161.708680392343, 4238.491381034296, 4240.766757828287, 5889.558283128783, 5903.26209223847, 5908.054711246266, 5926.318473375617, 5933.465988061095, 6103.6032657877095, 6114.205153330038, 6134.386561343919, 6138.478229350555, 6144.0, 5759.7408116634015, 5791.681457519792, 5927.081302398135, 5935.703214839327, 5940.554458415636, 5202.193659982709, 5212.687238765686, 5246.029484545371, 5298.841034768963, 5302.125149350391, 6460.353964603596, 6460.386263305072, 6466.030095485715, 6466.094746735461, 6472.417482426604, 5850.23946886902, 6173.0, 6178.938210617836, 6182.907256391248, 6188.845278868064, 6253.249610046823, 6371.585846919936, 6403.51973601991, 6491.1236983008575, 6522.836929076811, 6097.268327800515, 6104.969475152596, 6108.389161083856, 6109.877802444015, 6112.602680825732, 6206.534509911684, 6228.190335256388, 6231.22109736283, 6231.626102433868, 6238.906416403848, 5688.7440065198, 5791.131330300489, 5872.0, 5880.764769409176, 5974.0, 5878.147668587945, 5940.376260492586, 5940.79207227745, 5942.821715348547, 5970.522358211246, 5780.104083867007, 5946.108083787466, 5946.524278057832, 5947.494462970696, 5950.077737950624, 5874.236349274567, 5921.082232254007, 5972.790952316822, 6026.0659597762415, 6069.484093852072, 5917.171595976481, 5922.318933322551, 5926.555508336978, 5928.762849485972, 5931.851703707442], "value": 5649.785154657963, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.476929226311, 780.9140994490605, 795.0340336491162, 866.2420382165609, 875.5972252763728, 32077.839610801944, 32566.511502327456, 35201.82399088004, 35207.0, 45232.547674523244, 46004.079918401694, 46578.30132548015, 46600.0, 46645.76677116618, 46656.533434665645, 9184.586693598787, 9341.11259430353, 9341.252699784001, 9348.906510934872, 9355.953220233889, 7529.360004399605, 7703.499272547267, 9126.543672816366, 9227.3079519036, 10682.359058456479, 11053.834192487118, 11086.944565277163, 11096.778064438748, 11106.0, 11112.722181945435, 10989.230753847274, 11005.944970275177, 11016.17378696601, 11023.1732620053, 11030.944845275762, 10783.0, 10788.08301294391, 10874.18443616732, 10883.23817332787, 10890.292131011538, 43111.78444107791, 43228.78385608083, 43286.56713432857, 43363.56636433627, 43500.0, 4118.320477121271, 4159.251334759742, 4164.167166566699, 4263.232618128735, 4298.075913678543, 29847.403051939058, 30112.69887301121, 30365.848170759225, 30439.391212175855, 30718.385632287453, 4315.180115778024, 4436.201483732927, 4441.644668426518, 4473.686841921067, 4521.434820647419, 243893.0, 244016.0, 248997.0, 249965.0, 250461.7476912622, 54911.45088549143, 55184.44815551873, 55617.44382556203, 55671.164932526044, 55757.0, 57827.0, 58076.70961645165, 58097.0, 58097.70951145217, 58116.0, 15864.206789660471, 15889.920550397173, 15891.125988070768, 15901.761473577906, 15964.521064368088, 13824.585262442142, 13826.792598111037, 13834.0, 13842.584722458343, 13861.722765544633, 11987.0, 12323.198992065487, 13666.18002919828, 14121.717565648629, 14311.57065288043, 5672.914906276409, 5682.687452190169, 5726.398728133569, 5756.884862302731, 5832.941670583325, 1028.4292217819182, 1047.2512153810062, 1047.5233768635253, 1047.9528421221064, 1057.7884423115408, 28763.42473150567, 28878.42243155125, 28888.71111288902, 28898.566521502195, 29340.26649333741, 1084.9728756781144, 1101.5648818716722, 1115.4311301236266, 1117.7708569743183, 1132.6432173865205, 15002.02486838352, 15311.0, 15421.151836649093, 15424.151671657943, 15680.215989200495, 291346.0, 293736.0, 294083.0, 294588.0, 294863.0, 58579.12131318034, 58723.41276587179, 59007.0, 59060.40939590549, 59149.0, 1532.9846701532842, 1536.8386319436522, 1557.128008315347, 1581.533447632951, 1581.8215429505092, 26237.606435903857, 27135.59296610513, 27682.169534913985, 27895.442091158468, 28173.859130704215, 42899.142017160106, 42952.0, 43023.56976430196, 43025.0, 43138.78430607827, 278229.0, 279855.0, 280448.0, 280529.0, 281173.0, 35068.64931350654, 35946.8202658985, 36009.279814404086, 36035.0, 36216.81891590525, 36842.81578592197, 37381.813090934374, 41911.0, 44352.0, 44420.77789611031, 34998.65001349954, 35195.29609407849, 35334.0, 35401.82299088488, 35660.821695891354, 34931.30137397187, 34933.0, 35113.648863511036, 35270.0, 35280.11799705028, 40279.19441611093, 40458.39312410375, 41121.0, 41275.38086928638, 41534.0, 33346.4998025025, 33369.666303337624, 33928.491072634424, 33936.490952635235, 34000.0, 26716.465670686088, 26980.0, 27320.590191147545, 27381.178564642294, 27410.862945685145], "value": 19180.033278602466, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.37572150432396, 452.85508637236074, 460.4405647138728, 476.1310608140144, 476.50443538719753, 15087.471938482155, 15628.609284767886, 20053.59892802143, 20459.386218413452, 21002.26492072778, 20705.17179312826, 20962.685559716578, 21652.88595166921, 22171.113155473802, 22363.217287394946, 6813.965930170342, 6973.232944376115, 7004.194517630462, 7009.579425234477, 7018.438524917994, 5190.844274671747, 6706.530542862001, 6833.487488438387, 6885.380315771579, 6928.030075789394, 6919.65401729914, 6919.688614012382, 6920.342567456109, 6924.822780127365, 6927.480438967073, 7076.32774886385, 7320.304571065741, 7352.264773522658, 7356.043714317105, 7357.3378395944355, 7218.711251549932, 7307.926920730778, 7377.668004939791, 7403.888941665826, 7427.294407031323, 24565.50868982628, 24696.13563525286, 24989.750102498925, 25248.368790780194, 25266.24201273946, 3007.639083309995, 3225.8387080645875, 3273.018094571619, 3492.4412094065083, 3691.3724666806706, 15479.380824767108, 15954.202289885576, 16065.518034458984, 16092.27584758677, 16102.114383708891, 2213.8419922585326, 2221.922232721863, 2264.445210923318, 2266.512699769541, 2298.402415371999, 77613.22386776116, 79772.20227797765, 79893.60053199755, 94400.52799736026, 115898.42050789692, 17331.65336693259, 17978.190981405867, 18037.7294340585, 18248.270069197348, 18254.543636409195, 31699.0, 31849.52225716616, 31872.362552748815, 31984.680153198635, 32274.677253227168, 10035.548400322003, 10048.949755251177, 10058.949705251573, 10148.391096534231, 10268.794624107626, 8296.543690096982, 8363.414560980795, 8384.958075209706, 8537.402381833337, 8558.101399353103, 8946.284297256334, 8996.775080622905, 9080.81838363229, 9153.77115572116, 9352.345335826563, 3760.7571431423708, 3833.865814696528, 3843.6925045996254, 3875.7093218008763, 3933.688679198336, 958.8753462049889, 961.4952150121103, 968.7287559483356, 969.3263182088381, 981.1954197557934, 19540.413787586396, 20927.8953605231, 20966.58066838655, 20967.790322096585, 20977.580448390945, 986.225812736998, 988.8763904511936, 998.5107297424355, 1010.2978429991085, 1012.7215015870547, 10791.892081079246, 11174.217804753589, 11203.887961120448, 11285.153613479022, 11800.940995294968, 699841.0, 701918.0, 720905.0, 722444.0, 723523.0, 115640.0, 116753.41623291999, 119732.0, 122043.38978305052, 124335.0, 1275.2284867654935, 1356.0846428660614, 1357.3959587983252, 1366.3851266929921, 1367.0703921333588, 16803.243854026354, 18141.27434902642, 18746.71879921775, 18849.24603015919, 18866.151023203714, 51083.0, 52005.7399712999, 52076.739616301675, 52432.21351679652, 52798.47201527935, 68165.65917170382, 68803.65598171977, 69305.0, 70278.29721702718, 71364.64317678378, 10922.508487118257, 11058.447077645924, 11961.700957476132, 11982.64052078439, 12067.517299307929, 11314.886851131383, 12060.155789094679, 12198.81701774492, 12642.936785316015, 13109.0, 10508.894911050791, 11226.887731122584, 11391.031762299966, 11488.19582629208, 11489.942550287195, 10476.057154855795, 10583.470826458492, 10740.409277489813, 10790.1367890567, 10956.0, 11546.42267886619, 11571.479283432098, 11622.418879055844, 11721.648350549498, 11817.231879927776, 12981.286029268482, 13515.864841351853, 13717.931410342884, 13979.0, 14066.367013484622, 11532.308061516336, 11902.761944761229, 12084.697882553006, 12122.090843186972, 12302.938485307515], "value": 11294.019861309578, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1507.9773803392943, 1535.0866234590414, 1563.7732528783326, 1568.882333824963, 1582.6122599963012, 11014.118870490362, 11780.410979451037, 11784.941075294622, 11786.528538858445, 11793.7641247175, 10972.670819875397, 11225.550977960893, 11232.887671123286, 11240.100791936646, 11538.480768365424, 2404.362843846382, 2437.2322718343753, 2441.9755802441928, 2443.059422122478, 2444.4255599934, 2346.636271377939, 2385.7256415512265, 2444.10790061628, 2454.226918520669, 2459.0409740201335, 2360.0441821062495, 2366.550355432471, 2387.7254115776736, 2444.168982545934, 2445.1319781477587, 2332.7667233276707, 2368.988155059222, 2374.8931298091543, 2375.0, 2446.4617784087473, 2345.1205797825814, 2348.236823032504, 2357.5167090746527, 2358.6697862299293, 2359.3393849722106, 11245.437728113644, 11510.0, 11517.36654484003, 11518.654440366803, 11529.077673786078, 5698.7435565399655, 5841.474267315962, 5850.707464626794, 5857.648541087506, 5857.882842343172, 3305.190228394059, 3373.8650453981813, 3412.658734126592, 3421.2815308784934, 3432.673895979878, 1314.66476048607, 1351.5607427586074, 1358.8844948179417, 1385.3973521518171, 1399.4052527675697, 15070.09579425238, 15487.457938972195, 16154.353825847069, 17533.0, 17732.46802595911, 4533.818647254139, 4599.379083823641, 4715.127701375257, 4717.976410117927, 4746.1931471649905, 10938.56245750177, 11207.103431725442, 12157.209781364183, 13080.411381487856, 13120.409581568852, 5792.391798861059, 5888.146218798251, 5902.409759024149, 5903.498202652737, 5904.1734157218025, 5405.324334458192, 5436.374816896107, 5472.699001554873, 5477.20580515823, 5499.670019798825, 3898.2983063048355, 4005.6581045349553, 4035.636792688672, 4050.250703619781, 4062.73592216505, 994.0506815990711, 995.2734503812258, 1003.82934901067, 1009.0968583118068, 1012.0335079998583, 855.6449073634373, 889.7197382824389, 891.4250308550949, 897.9146981036791, 919.1773362840221, 7041.295870413021, 7131.429485641136, 7133.964330178316, 7140.892886606705, 7222.349988501061, 1134.6993046842592, 1140.589387820384, 1146.5643055638805, 1153.1581945179898, 1153.7231064544449, 4607.861764147081, 4723.385959825201, 4727.952720472751, 4787.760611969387, 4798.424189097261, 14223.857761422254, 14885.957982941307, 14913.92543037278, 14917.328720207617, 14930.701385972437, 14150.716985660229, 14343.713125737428, 14521.636959075897, 14565.41738330455, 14578.416863325348, 10421.843672344921, 10722.41026743521, 10754.247202695893, 10806.621768238074, 10826.296290741077, 7509.7371591994015, 7574.772756817304, 7721.845563088819, 7742.4193185510185, 7792.727254546064, 12816.295103769382, 13049.282289473791, 13091.149075310075, 13121.803172952223, 13206.867931320949, 11656.358900260568, 13514.121582097132, 13517.121387109806, 13537.0, 13672.794908076587, 8734.213920747163, 9511.429314241168, 9694.709158725249, 9882.604695812088, 9983.850242246517, 11016.06363459115, 11038.227324087558, 11118.110551156049, 11121.944390277997, 11182.944085279521, 9289.396189247676, 9306.860397093913, 9338.066193380879, 9355.298352623444, 9493.193078588121, 9128.406653567497, 9203.953980230324, 9219.44683319003, 9354.064593540592, 9366.203872670627, 8232.053313868983, 8250.587470626564, 8286.875696864432, 8363.665453381796, 8425.62084706202, 6115.510759139169, 6232.376762323913, 6234.594751341329, 6401.199850018747, 6421.71102300407, 6267.373262673696, 6425.742970281323, 6522.5760325578685, 6655.267920528641, 6699.598024118569], "value": 5371.41124444794, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.73093085890457, 424.5499770243541, 425.0372905369337, 427.25444100045405, 429.7378599054574, 5280.445553216915, 5317.760700768465, 5341.385740639827, 5351.866203344918, 5361.705106219161, 4845.733484658343, 5015.899682006367, 5086.720230387327, 5165.457626949173, 5213.009528189641, 4360.869173924787, 4363.280058790296, 4390.604845563898, 4418.64650827933, 4423.557644235567, 4412.859763014952, 4415.580519850625, 4434.157510073076, 4440.400545926307, 4482.327650852382, 4276.572342765729, 4280.293751530994, 4308.9353659695125, 4453.6659750518675, 4460.576245256695, 4419.779011049467, 4431.559743083479, 4431.911361772746, 4457.777111144463, 4468.50846406895, 4264.360345948133, 4266.8052945175405, 4280.700350975433, 4283.87148385549, 4289.970407102304, 5013.423456302536, 5039.790450291904, 5075.492450754932, 5151.381834179922, 5192.247124167013, 5176.844694659167, 5213.296205012312, 5233.581313494911, 5270.919461510382, 5281.141349958048, 5529.4470552944385, 5535.972320138414, 5536.764375252935, 5551.306086739158, 5584.441555844423, 4454.621357184643, 4591.173588754023, 4625.236835922069, 4813.302071199691, 4838.927416088762, 5647.604667673223, 5729.762684083479, 5735.483806457397, 5736.311642602915, 5751.338596061465, 4738.763061846963, 4749.572538471554, 4765.213739732905, 4772.308015337757, 4810.0, 4681.133990211822, 4707.870111173363, 4789.880252993703, 4824.201962861532, 4869.610431165569, 5042.243663450512, 5067.315912351785, 5071.554606937005, 5135.974320128375, 5179.741012949337, 5507.862303442366, 5515.834524964257, 5545.753969642221, 5608.802982300132, 5616.438356164351, 1607.686501132268, 1644.2817255967543, 1661.7258152404959, 1671.3620651761341, 1682.1168886334772, 1183.207251141737, 1207.4506099724604, 1252.6680429686141, 1273.802560603108, 1309.685675437888, 5405.459454054563, 5428.4028756836715, 5447.754851031713, 5479.945200548023, 5498.670079795225, 5520.199571062173, 5613.354464236665, 5651.689157096317, 5653.491185793216, 5667.0, 5131.589472842238, 5414.458554144554, 5433.375161856437, 5457.972710136424, 5473.2063850742215, 2963.7628989680775, 2978.5532170174647, 3001.5047517159423, 3008.9548656770166, 3017.5473678948333, 5576.553875689935, 5744.856378590485, 5818.796342128006, 5820.796272130455, 5845.473907348359, 5773.971130144322, 5774.711264436762, 5783.872144931782, 5796.88406231873, 5808.912866307009, 5934.406559344117, 5941.287045554561, 5950.2264705587995, 5995.970020149872, 6010.819675409744, 5428.918566221589, 5604.635698679573, 5627.21219029328, 5671.6597004179885, 5756.71216439168, 5596.608237423341, 5607.831765047055, 5625.46558076982, 5641.26663533738, 5647.604667673388, 5627.396192085236, 5879.441453061952, 5916.852078698067, 5917.644941303535, 5949.315828679757, 4707.17624415719, 4737.502562231054, 4738.739369334582, 4764.666473346832, 4778.354922085474, 5713.457221563945, 5737.971310143423, 5740.827775166752, 5765.336986246635, 5772.0, 5423.156126916867, 5546.142042415836, 5564.387917329172, 5578.888422231614, 5586.190002449704, 5503.504684578407, 5526.226328313959, 5526.618345413646, 5536.335639723259, 5565.415631358649, 5351.892962140658, 5453.481919217668, 5527.0, 5558.749856256559, 5583.832485025456, 5460.945390546202, 5465.59008074388, 5486.780528778804, 5493.807716729895, 5502.779888804403, 5415.431379705232, 5438.809641662523, 5446.809361672323, 5448.210009548672, 5470.398256191896], "value": 4462.706226774521, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [842.2798507276275, 858.5792961448892, 897.3673560140102, 903.9005709371968, 913.2191975860638, 15324.69350612987, 16078.758818617716, 16641.0, 16701.83298167018, 16744.246508907097, 14691.55925322242, 14751.041182323168, 14853.108813471199, 15235.162066086361, 15344.539663810103, 857.5069335132308, 871.2289623683055, 873.2271939333672, 873.2620935309653, 873.5239294584467, 854.9572521373938, 871.9738407847775, 885.8538341173698, 885.9158379953926, 886.9068747781455, 934.5420743835506, 936.2463217110258, 936.3585943628602, 936.4802534593298, 937.643695395752, 925.3799954030816, 926.578406824893, 927.7216834949522, 928.3547934185743, 928.4475736936512, 684.384054351083, 685.3044160177424, 685.3763075601179, 685.4482141875789, 685.5852209413333, 16164.272607732562, 16497.505074847773, 16550.255238514295, 16873.325066997306, 17057.82942170575, 15085.773713394308, 16086.75869861953, 16123.355065797472, 16380.26288817006, 16624.25190866414, 12871.543549286818, 13009.739805203937, 13286.26925519096, 13384.263865487394, 13496.93251533746, 3917.7453465524936, 3925.941110883339, 3933.567307596162, 3982.3628219484744, 4058.370952502367, 88259.0, 88908.0, 89052.0, 89348.63908165596, 90666.5466672669, 12407.093460871165, 12650.150336704557, 12815.807762883564, 13103.475860965455, 13197.802032969514, 24014.15950441726, 25345.493090138094, 25380.87309563477, 25724.356891077874, 25747.227583172535, 8846.380753347332, 8923.15230053144, 9189.862152067724, 9286.888981354849, 9438.952805236067, 5127.282180494659, 5238.345206849144, 5281.049411106038, 5317.7341132943975, 5359.330083739533, 3554.431290993429, 3607.1703508193264, 3622.3660859350034, 3644.6173151818675, 3657.926841463156, 590.6279044202211, 591.4588151841017, 597.9222701048836, 600.1717629671114, 605.1286147946943, 729.7701224114387, 734.0897287363738, 748.655618415524, 753.2655660730744, 779.1351599724302, 9869.457179855177, 10181.745456363502, 10368.170546356274, 10962.835557466644, 10983.505742241618, 699.720111955222, 711.9679614417364, 729.0631538473036, 734.2253922112117, 745.3552676934413, 4966.255061740696, 5026.321446604735, 5042.773075211624, 5062.443131255559, 5064.290999260106, 66261.66869165719, 80595.0, 81548.0, 82955.0, 85164.0, 66253.33746662569, 67164.3283567158, 67461.0, 67486.0, 68550.0, 61513.0, 63676.68161659162, 64594.0, 64708.352916470234, 65452.67273663697, 11415.828762568734, 12051.216670916363, 12082.939585302018, 12362.3818809061, 12462.813057803958, 37922.0, 38338.6166138335, 38506.80746596249, 43945.560544394146, 44644.33033504435, 55559.44440555543, 56505.86988260294, 57254.7137264311, 57986.42013579979, 60018.0, 12674.936625317183, 12804.48782048745, 12935.0, 13044.673883152996, 13065.608031759062, 14941.626459338604, 15125.243737812845, 15208.695826083194, 15312.846871531588, 15556.455524056604, 15703.371865125268, 15933.20333983273, 15944.840551594336, 16125.677486449971, 16181.271842767339, 14264.144151350953, 14649.340779664728, 14872.776908346166, 15075.170865602497, 15554.144522051396, 14897.0, 14932.925335373253, 15098.0, 15255.466058687893, 15330.846691532943, 10772.0, 10947.781044378908, 11007.889921101007, 11287.7742445149, 11359.659210223706, 12539.561115360919, 12746.235225886476, 12940.741185176432, 12991.0, 13286.80069798973], "value": 7773.355439628093, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1457.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 113.0, 114.0, 114.0, 115.0, 114.0, 113.0, 112.0, 111.0, 113.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 115.0, 115.0, 151.0, 117.0, 123.0, 116.0, 115.0, 131.0, 115.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 163.0, 160.0, 163.0, 161.0, 160.0, 161.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 115.0, 76.0, 76.0, 76.0, 76.0, 89.0, 76.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 101.0, 83.0, 83.0, 83.0, 94.0, 84.0, 82.0, 93.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 55.0, 53.0, 55.0, 55.0, 54.0, 55.0, 54.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 53.0, 53.0, 54.0, 54.0, 53.0, 54.0], "value": 53.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 79.0, 86.0, 76.0, 78.0, 79.0, 81.0, 85.0, 76.0, 85.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 120.0, 128.0, 121.0, 133.0, 134.0, 119.0, 119.0, 160.0, 120.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 166.0, 161.0, 160.0, 162.0, 161.0, 163.0, 160.0, 159.0, 158.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 59.0, 60.0, 60.0, 65.0, 59.0, 59.0, 59.0], "value": 60.0, "unit": "score"}]}]} 01:56:45 INFO - Return code: 0 01:56:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:56:45 ERROR - # TBPL SUCCESS # 01:56:45 INFO - Running post-action listener: _resource_record_post_action 01:56:45 INFO - [mozharness: 2017-04-06 08:56:45.985020Z] Finished run-tests step (success) 01:56:45 INFO - Running post-run listener: _resource_record_post_run 01:56:46 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 303104; Write bytes: 364171264; Read time: 276; Write time: 270520 01:56:46 INFO - TinderboxPrint: CPU usage
13.1% 01:56:46 INFO - TinderboxPrint: I/O read bytes / time
303,104 / 276 01:56:46 INFO - TinderboxPrint: I/O write bytes / time
364,171,264 / 270,520 01:56:46 INFO - TinderboxPrint: CPU idle
8,271.6 (86.9%) 01:56:46 INFO - TinderboxPrint: CPU user
1,200.1 (12.6%) 01:56:46 INFO - TinderboxPrint: Swap in / out
0 / 0 01:56:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110878720; Read time: 0; Write time: 103060 01:56:46 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 303104; Write bytes: 253292544; Read time: 276; Write time: 167460 01:56:46 INFO - Running post-run listener: _upload_blobber_files 01:56:46 INFO - Blob upload gear active. 01:56:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:56:46 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 01:56:46 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'] 01:56:46 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 01:56:48 INFO - (blobuploader) - INFO - Open directory for files ... 01:56:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:56:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:56:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:50 INFO - (blobuploader) - INFO - Done attempting. 01:56:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:56:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:56:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:57 INFO - (blobuploader) - INFO - Done attempting. 01:56:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:56:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:57:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:01 INFO - (blobuploader) - INFO - Done attempting. 01:57:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:57:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:57:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:57:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:57:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:57:03 INFO - (blobuploader) - INFO - Done attempting. 01:57:03 INFO - (blobuploader) - INFO - Iteration through files over. 01:57:03 INFO - Return code: 0 01:57:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:57:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:57:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f5231dec3a1e1f7f8435bf6334d8e7add2936e2a391fa5565e6582a6e270ae14e47eac8a3b55cceff724d0e0747db6795dee77139987a515852054e4be48647a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/aef5b18dc7b88ac84659bd082c2ec3af92072d80cfe1433fe7620e2b38f08dff39795c560304064cb216702035f8b7973e17af542f10695c92a5b412332f2b2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/53a48843afb7eda0571d4c316b2b1c9c19fd43d51b14e9fada99e6d3aa7898d23c6f926ffc8862f672d3a25857a2e33b31cd725ed5a35ae5176e510f0f4f048a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1f3e37cb3d2a6b3a22a9bfa61bad2ea87fbb387dfa4f962d5ac3769da54f7f2d2d11b32e9775192ef99c2f90b245b013baf72bf297fcd102f860df81c10b5505"} 01:57:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:57:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:57:03 INFO - Contents: 01:57:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f5231dec3a1e1f7f8435bf6334d8e7add2936e2a391fa5565e6582a6e270ae14e47eac8a3b55cceff724d0e0747db6795dee77139987a515852054e4be48647a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/aef5b18dc7b88ac84659bd082c2ec3af92072d80cfe1433fe7620e2b38f08dff39795c560304064cb216702035f8b7973e17af542f10695c92a5b412332f2b2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/53a48843afb7eda0571d4c316b2b1c9c19fd43d51b14e9fada99e6d3aa7898d23c6f926ffc8862f672d3a25857a2e33b31cd725ed5a35ae5176e510f0f4f048a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1f3e37cb3d2a6b3a22a9bfa61bad2ea87fbb387dfa4f962d5ac3769da54f7f2d2d11b32e9775192ef99c2f90b245b013baf72bf297fcd102f860df81c10b5505"} 01:57:03 INFO - Running post-run listener: copy_logs_to_upload_dir 01:57:03 INFO - Copying logs to upload dir... 01:57:03 INFO - mkdir: /builds/slave/test/build/upload/logs 01:57:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.116549 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-04-06 01:57:03.828624) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 01:57:03.832497) ========= 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=cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679 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/f5231dec3a1e1f7f8435bf6334d8e7add2936e2a391fa5565e6582a6e270ae14e47eac8a3b55cceff724d0e0747db6795dee77139987a515852054e4be48647a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/aef5b18dc7b88ac84659bd082c2ec3af92072d80cfe1433fe7620e2b38f08dff39795c560304064cb216702035f8b7973e17af542f10695c92a5b412332f2b2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/53a48843afb7eda0571d4c316b2b1c9c19fd43d51b14e9fada99e6d3aa7898d23c6f926ffc8862f672d3a25857a2e33b31cd725ed5a35ae5176e510f0f4f048a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1f3e37cb3d2a6b3a22a9bfa61bad2ea87fbb387dfa4f962d5ac3769da54f7f2d2d11b32e9775192ef99c2f90b245b013baf72bf297fcd102f860df81c10b5505"} build_url:https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/RxyyxJ8NSKmicp2hYbE_Tw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/f5231dec3a1e1f7f8435bf6334d8e7add2936e2a391fa5565e6582a6e270ae14e47eac8a3b55cceff724d0e0747db6795dee77139987a515852054e4be48647a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/aef5b18dc7b88ac84659bd082c2ec3af92072d80cfe1433fe7620e2b38f08dff39795c560304064cb216702035f8b7973e17af542f10695c92a5b412332f2b2c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/53a48843afb7eda0571d4c316b2b1c9c19fd43d51b14e9fada99e6d3aa7898d23c6f926ffc8862f672d3a25857a2e33b31cd725ed5a35ae5176e510f0f4f048a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1f3e37cb3d2a6b3a22a9bfa61bad2ea87fbb387dfa4f962d5ac3769da54f7f2d2d11b32e9775192ef99c2f90b245b013baf72bf297fcd102f860df81c10b5505"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-06 01:57:03.857278) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 01:57:03.857560) ========= 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=cbe409c6de289db6ac707de3000001d4-1491467751.422109-449259679 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-06 01:57:03.942721) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 01:57:03.943014) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-06 01:57:03.943295) ========= ========= Total master_lag: 0.16 =========